[18:16:20.126] New invocation is queued and will start shortly
[18:16:21.204] Starting the invocation (attempt 1)
[18:16:21.261] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:21.261] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:21.262] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081247018092994512",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a5dd08cac2991bbfcb304c8d4fc485381ff5f751\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081247018092994512",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"gitiles_ref:refs/heads/master"
]
}
[18:16:22.277] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555611381347423",
"id": "8915816956151067344",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a5dd08cac2991bbfcb304c8d4fc485381ff5f751\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555611381944720",
"tags": [
"builder:clang",
"buildset:commit/git/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081247018092994512",
"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/8915816956151067344/+/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": "1555611381944855",
"url": "https://ci.chromium.org/b/8915816956151067344",
"utcnow_ts": "1555611382265442"
}
}
[18:16:22.277] Task URL: https://ci.chromium.org/b/8915816956151067344
[18:16:22.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:2:0) after 1m0s
[18:16:28.302] Received PubSub notification, asking Buildbucket for the build status
[18:16:28.351] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:22.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:2:0)
[18:17:22.305] Timer tick, asking Buildbucket for the build status
[18:17:22.387] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:22.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:4:0) after 1m0s
[18:18:22.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:4:0)
[18:18:22.393] Timer tick, asking Buildbucket for the build status
[18:18:22.434] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:22.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:5:0) after 1m0s
[18:19:22.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:5:0)
[18:19:22.426] Timer tick, asking Buildbucket for the build status
[18:19:22.594] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:22.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:6:0) after 1m0s
[18:20:22.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:6:0)
[18:20:22.588] Timer tick, asking Buildbucket for the build status
[18:20:22.879] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:22.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:7:0) after 1m0s
[18:21:22.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:7:0)
[18:21:22.904] Timer tick, asking Buildbucket for the build status
[18:21:23.280] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:23.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:8:0) after 1m0s
[18:22:23.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:8:0)
[18:22:23.274] Timer tick, asking Buildbucket for the build status
[18:22:23.316] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:23.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:9:0) after 1m0s
[18:23:23.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:9:0)
[18:23:23.330] Timer tick, asking Buildbucket for the build status
[18:23:23.409] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:23.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:10:0) after 1m0s
[18:24:23.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:10:0)
[18:24:23.454] Timer tick, asking Buildbucket for the build status
[18:24:23.519] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:23.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:11:0) after 1m0s
[18:25:23.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:11:0)
[18:25:23.541] Timer tick, asking Buildbucket for the build status
[18:25:23.660] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:23.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:12:0) after 1m0s
[18:26:23.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:12:0)
[18:26:23.685] Timer tick, asking Buildbucket for the build status
[18:26:23.901] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:23.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:13:0) after 1m0s
[18:27:23.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:13:0)
[18:27:23.929] Timer tick, asking Buildbucket for the build status
[18:27:24.061] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:24.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:14:0) after 1m0s
[18:28:24.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:14:0)
[18:28:24.153] Timer tick, asking Buildbucket for the build status
[18:28:24.210] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:24.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:15:0) after 1m0s
[18:29:24.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:15:0)
[18:29:24.191] Timer tick, asking Buildbucket for the build status
[18:29:24.257] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:24.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:16:0) after 1m0s
[18:30:24.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:16:0)
[18:30:24.275] Timer tick, asking Buildbucket for the build status
[18:30:24.576] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:24.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:17:0) after 1m0s
[18:31:24.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:17:0)
[18:31:24.550] Timer tick, asking Buildbucket for the build status
[18:31:24.591] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:24.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:18:0) after 1m0s
[18:32:24.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:18:0)
[18:32:24.580] Timer tick, asking Buildbucket for the build status
[18:32:24.620] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:24.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:19:0) after 1m0s
[18:33:24.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:19:0)
[18:33:24.601] Timer tick, asking Buildbucket for the build status
[18:33:24.700] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:24.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:20:0) after 1m0s
[18:34:24.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:20:0)
[18:34:24.718] Timer tick, asking Buildbucket for the build status
[18:34:25.005] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:25.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:21:0) after 1m0s
[18:35:25.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:21:0)
[18:35:25.026] Timer tick, asking Buildbucket for the build status
[18:35:25.065] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:25.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:22:0) after 1m0s
[18:36:25.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:22:0)
[18:36:25.149] Timer tick, asking Buildbucket for the build status
[18:36:25.194] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:25.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:23:0) after 1m0s
[18:37:25.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:23:0)
[18:37:25.233] Timer tick, asking Buildbucket for the build status
[18:37:25.362] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:25.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:24:0) after 1m0s
[18:38:25.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:24:0)
[18:38:25.389] Timer tick, asking Buildbucket for the build status
[18:38:25.434] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:25.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:25:0) after 1m0s
[18:39:25.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:25:0)
[18:39:25.447] Timer tick, asking Buildbucket for the build status
[18:39:25.572] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:25.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:26:0) after 1m0s
[18:40:25.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:26:0)
[18:40:25.585] Timer tick, asking Buildbucket for the build status
[18:40:25.640] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:25.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:27:0) after 1m0s
[18:41:25.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:27:0)
[18:41:25.660] Timer tick, asking Buildbucket for the build status
[18:41:25.730] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:25.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:28:0) after 1m0s
[18:42:25.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:28:0)
[18:42:25.755] Timer tick, asking Buildbucket for the build status
[18:42:25.842] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:25.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:29:0) after 1m0s
[18:43:25.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:29:0)
[18:43:25.821] Timer tick, asking Buildbucket for the build status
[18:43:25.979] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:25.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:30:0) after 1m0s
[18:44:26.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:30:0)
[18:44:26.001] Timer tick, asking Buildbucket for the build status
[18:44:26.116] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:26.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:31:0) after 1m0s
[18:45:26.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:31:0)
[18:45:26.111] Timer tick, asking Buildbucket for the build status
[18:45:26.172] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:26.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:32:0) after 1m0s
[18:46:26.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:32:0)
[18:46:26.190] Timer tick, asking Buildbucket for the build status
[18:46:26.244] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:26.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:33:0) after 1m0s
[18:47:26.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:33:0)
[18:47:26.328] Timer tick, asking Buildbucket for the build status
[18:47:26.443] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:26.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:34:0) after 1m0s
[18:48:26.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:34:0)
[18:48:26.462] Timer tick, asking Buildbucket for the build status
[18:48:26.584] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:26.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:35:0) after 1m0s
[18:49:26.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:35:0)
[18:49:26.605] Timer tick, asking Buildbucket for the build status
[18:49:26.645] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:26.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:36:0) after 1m0s
[18:50:26.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:36:0)
[18:50:26.678] Timer tick, asking Buildbucket for the build status
[18:50:26.725] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:26.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:37:0) after 1m0s
[18:51:26.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:37:0)
[18:51:26.759] Timer tick, asking Buildbucket for the build status
[18:51:26.846] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:26.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:38:0) after 1m0s
[18:52:26.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:38:0)
[18:52:26.840] Timer tick, asking Buildbucket for the build status
[18:52:26.884] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:26.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:39:0) after 1m0s
[18:53:26.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:39:0)
[18:53:26.886] Timer tick, asking Buildbucket for the build status
[18:53:27.028] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:27.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:40:0) after 1m0s
[18:54:27.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:40:0)
[18:54:27.128] Timer tick, asking Buildbucket for the build status
[18:54:27.167] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:27.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:41:0) after 1m0s
[18:55:27.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:41:0)
[18:55:27.374] Timer tick, asking Buildbucket for the build status
[18:55:27.443] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:27.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:42:0) after 1m0s
[18:56:27.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:42:0)
[18:56:27.476] Timer tick, asking Buildbucket for the build status
[18:56:27.582] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:27.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:43:0) after 1m0s
[18:57:27.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:43:0)
[18:57:27.611] Timer tick, asking Buildbucket for the build status
[18:57:27.771] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:27.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:44:0) after 1m0s
[18:58:27.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:44:0)
[18:58:27.741] Timer tick, asking Buildbucket for the build status
[18:58:27.817] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:27.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:45:0) after 1m0s
[18:59:27.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:45:0)
[18:59:27.842] Timer tick, asking Buildbucket for the build status
[18:59:27.889] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:27.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:46:0) after 1m0s
[19:00:27.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:46:0)
[19:00:27.996] Timer tick, asking Buildbucket for the build status
[19:00:28.063] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:28.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:47:0) after 1m0s
[19:01:28.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:47:0)
[19:01:28.105] Timer tick, asking Buildbucket for the build status
[19:01:28.189] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:28.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:48:0) after 1m0s
[19:02:28.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:48:0)
[19:02:28.218] Timer tick, asking Buildbucket for the build status
[19:02:28.270] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:28.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:49:0) after 1m0s
[19:03:28.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:49:0)
[19:03:28.287] Timer tick, asking Buildbucket for the build status
[19:03:28.388] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:28.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:50:0) after 1m0s
[19:04:28.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:50:0)
[19:04:28.401] Timer tick, asking Buildbucket for the build status
[19:04:28.520] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:28.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:51:0) after 1m0s
[19:05:28.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:51:0)
[19:05:28.540] Timer tick, asking Buildbucket for the build status
[19:05:28.596] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:28.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:52:0) after 1m0s
[19:06:28.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:52:0)
[19:06:28.603] Timer tick, asking Buildbucket for the build status
[19:06:28.734] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:28.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:53:0) after 1m0s
[19:07:28.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:53:0)
[19:07:28.718] Timer tick, asking Buildbucket for the build status
[19:07:29.003] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:29.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:54:0) after 1m0s
[19:08:28.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:54:0)
[19:08:28.989] Timer tick, asking Buildbucket for the build status
[19:08:29.033] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:29.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:55:0) after 1m0s
[19:09:29.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:55:0)
[19:09:29.080] Timer tick, asking Buildbucket for the build status
[19:09:29.123] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:29.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:56:0) after 1m0s
[19:10:29.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:56:0)
[19:10:29.145] Timer tick, asking Buildbucket for the build status
[19:10:29.209] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:29.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:57:0) after 1m0s
[19:11:29.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:57:0)
[19:11:29.230] Timer tick, asking Buildbucket for the build status
[19:11:30.675] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:58:0) after 1m0s
[19:12:30.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:58:0)
[19:12:30.698] Timer tick, asking Buildbucket for the build status
[19:12:30.883] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:30.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:59:0) after 1m0s
[19:13:30.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:59:0)
[19:13:30.905] Timer tick, asking Buildbucket for the build status
[19:13:31.005] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:31.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:60:0) after 1m0s
[19:14:31.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:60:0)
[19:14:31.049] Timer tick, asking Buildbucket for the build status
[19:14:31.157] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:31.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:61:0) after 1m0s
[19:15:31.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:61:0)
[19:15:31.190] Timer tick, asking Buildbucket for the build status
[19:15:31.245] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:31.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:62:0) after 1m0s
[19:16:31.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:62:0)
[19:16:31.231] Timer tick, asking Buildbucket for the build status
[19:16:31.691] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:31.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:63:0) after 1m0s
[19:17:31.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:63:0)
[19:17:31.715] Timer tick, asking Buildbucket for the build status
[19:17:31.762] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:31.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:64:0) after 1m0s
[19:18:31.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:64:0)
[19:18:31.785] Timer tick, asking Buildbucket for the build status
[19:18:31.877] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:31.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:65:0) after 1m0s
[19:19:31.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:65:0)
[19:19:31.898] Timer tick, asking Buildbucket for the build status
[19:19:31.938] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:31.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:66:0) after 1m0s
[19:20:31.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:66:0)
[19:20:31.929] Timer tick, asking Buildbucket for the build status
[19:20:32.069] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:32.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:67:0) after 1m0s
[19:21:32.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:67:0)
[19:21:32.169] Timer tick, asking Buildbucket for the build status
[19:21:32.234] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:32.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:68:0) after 1m0s
[19:22:32.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:68:0)
[19:22:32.402] Timer tick, asking Buildbucket for the build status
[19:22:32.546] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:32.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:69:0) after 1m0s
[19:23:32.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:69:0)
[19:23:32.589] Timer tick, asking Buildbucket for the build status
[19:23:32.851] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:32.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:70:0) after 1m0s
[19:24:32.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:70:0)
[19:24:32.857] Timer tick, asking Buildbucket for the build status
[19:24:32.914] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:32.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:71:0) after 1m0s
[19:25:32.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:71:0)
[19:25:32.917] Timer tick, asking Buildbucket for the build status
[19:25:32.983] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:32.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:72:0) after 1m0s
[19:26:32.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:72:0)
[19:26:32.965] Timer tick, asking Buildbucket for the build status
[19:26:33.013] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:33.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:73:0) after 1m0s
[19:27:33.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:73:0)
[19:27:33.047] Timer tick, asking Buildbucket for the build status
[19:27:33.096] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:33.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:74:0) after 1m0s
[19:28:33.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:74:0)
[19:28:33.200] Timer tick, asking Buildbucket for the build status
[19:28:33.320] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:33.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:75:0) after 1m0s
[19:29:33.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:75:0)
[19:29:33.365] Timer tick, asking Buildbucket for the build status
[19:29:33.415] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:33.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:76:0) after 1m0s
[19:30:33.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:76:0)
[19:30:33.403] Timer tick, asking Buildbucket for the build status
[19:30:33.446] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:33.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:77:0) after 1m0s
[19:31:33.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:77:0)
[19:31:33.486] Timer tick, asking Buildbucket for the build status
[19:31:33.554] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:33.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:78:0) after 1m0s
[19:32:33.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:78:0)
[19:32:33.591] Timer tick, asking Buildbucket for the build status
[19:32:33.656] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:33.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:79:0) after 1m0s
[19:33:33.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:79:0)
[19:33:33.685] Timer tick, asking Buildbucket for the build status
[19:33:33.732] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:33.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:80:0) after 1m0s
[19:34:33.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:80:0)
[19:34:33.753] Timer tick, asking Buildbucket for the build status
[19:34:33.796] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:33.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:81:0) after 1m0s
[19:35:33.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:81:0)
[19:35:33.778] Timer tick, asking Buildbucket for the build status
[19:35:33.820] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:33.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:82:0) after 1m0s
[19:36:33.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:82:0)
[19:36:33.864] Timer tick, asking Buildbucket for the build status
[19:36:33.909] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:33.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:83:0) after 1m0s
[19:37:33.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:83:0)
[19:37:33.921] Timer tick, asking Buildbucket for the build status
[19:37:33.975] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:33.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:84:0) after 1m0s
[19:38:33.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:84:0)
[19:38:33.981] Timer tick, asking Buildbucket for the build status
[19:38:34.105] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:34.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:85:0) after 1m0s
[19:39:34.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:85:0)
[19:39:34.127] Timer tick, asking Buildbucket for the build status
[19:39:34.229] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:34.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:86:0) after 1m0s
[19:40:34.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:86:0)
[19:40:34.251] Timer tick, asking Buildbucket for the build status
[19:40:34.296] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:34.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:87:0) after 1m0s
[19:41:34.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:87:0)
[19:41:34.318] Timer tick, asking Buildbucket for the build status
[19:41:34.405] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:34.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:88:0) after 1m0s
[19:42:34.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:88:0)
[19:42:34.431] Timer tick, asking Buildbucket for the build status
[19:42:34.494] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:34.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:89:0) after 1m0s
[19:43:34.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:89:0)
[19:43:34.517] Timer tick, asking Buildbucket for the build status
[19:43:34.604] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:34.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:90:0) after 1m0s
[19:44:34.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:90:0)
[19:44:34.620] Timer tick, asking Buildbucket for the build status
[19:44:34.723] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:34.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:91:0) after 1m0s
[19:45:34.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:91:0)
[19:45:34.743] Timer tick, asking Buildbucket for the build status
[19:45:34.809] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:34.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:92:0) after 1m0s
[19:46:34.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:92:0)
[19:46:34.832] Timer tick, asking Buildbucket for the build status
[19:46:34.912] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:34.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:93:0) after 1m0s
[19:47:34.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:93:0)
[19:47:34.931] Timer tick, asking Buildbucket for the build status
[19:47:34.992] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:34.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:94:0) after 1m0s
[19:48:35.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:94:0)
[19:48:35.143] Timer tick, asking Buildbucket for the build status
[19:48:35.346] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:35.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:95:0) after 1m0s
[19:49:35.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:95:0)
[19:49:35.369] Timer tick, asking Buildbucket for the build status
[19:49:35.418] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:35.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:96:0) after 1m0s
[19:50:35.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:96:0)
[19:50:35.469] Timer tick, asking Buildbucket for the build status
[19:50:35.642] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:35.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:97:0) after 1m0s
[19:51:35.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:97:0)
[19:51:35.674] Timer tick, asking Buildbucket for the build status
[19:51:35.848] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:35.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:98:0) after 1m0s
[19:52:35.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:98:0)
[19:52:35.852] Timer tick, asking Buildbucket for the build status
[19:52:35.916] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:35.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:99:0) after 1m0s
[19:53:35.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:99:0)
[19:53:35.907] Timer tick, asking Buildbucket for the build status
[19:53:35.957] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:35.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:100:0) after 1m0s
[19:54:35.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:100:0)
[19:54:35.949] Timer tick, asking Buildbucket for the build status
[19:54:36.016] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:36.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:101:0) after 1m0s
[19:55:36.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:101:0)
[19:55:36.089] Timer tick, asking Buildbucket for the build status
[19:55:36.280] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:36.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:102:0) after 1m0s
[19:56:36.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:102:0)
[19:56:36.301] Timer tick, asking Buildbucket for the build status
[19:56:36.525] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:36.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:103:0) after 1m0s
[19:57:36.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:103:0)
[19:57:36.548] Timer tick, asking Buildbucket for the build status
[19:57:36.681] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:36.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:104:0) after 1m0s
[19:58:36.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:104:0)
[19:58:36.727] Timer tick, asking Buildbucket for the build status
[19:58:36.903] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:36.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:105:0) after 1m0s
[19:59:36.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:105:0)
[19:59:36.922] Timer tick, asking Buildbucket for the build status
[19:59:37.001] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:37.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:106:0) after 1m0s
[20:00:37.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:106:0)
[20:00:37.045] Timer tick, asking Buildbucket for the build status
[20:00:37.093] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:37.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:107:0) after 1m0s
[20:01:37.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:107:0)
[20:01:37.077] Timer tick, asking Buildbucket for the build status
[20:01:37.370] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:37.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:108:0) after 1m0s
[20:02:37.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:108:0)
[20:02:37.411] Timer tick, asking Buildbucket for the build status
[20:02:37.479] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:37.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:109:0) after 1m0s
[20:03:37.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:109:0)
[20:03:37.511] Timer tick, asking Buildbucket for the build status
[20:03:37.551] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:37.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:110:0) after 1m0s
[20:04:37.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:110:0)
[20:04:37.583] Timer tick, asking Buildbucket for the build status
[20:04:37.767] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:37.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:111:0) after 1m0s
[20:05:37.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:111:0)
[20:05:37.793] Timer tick, asking Buildbucket for the build status
[20:05:37.847] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:37.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:112:0) after 1m0s
[20:06:37.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:112:0)
[20:06:37.835] Timer tick, asking Buildbucket for the build status
[20:06:37.910] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:37.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:113:0) after 1m0s
[20:07:38.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:113:0)
[20:07:38.013] Timer tick, asking Buildbucket for the build status
[20:07:38.119] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:38.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:114:0) after 1m0s
[20:08:38.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:114:0)
[20:08:38.155] Timer tick, asking Buildbucket for the build status
[20:08:38.220] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:38.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:115:0) after 1m0s
[20:09:38.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:115:0)
[20:09:38.203] Timer tick, asking Buildbucket for the build status
[20:09:38.246] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:38.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:116:0) after 1m0s
[20:10:38.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:116:0)
[20:10:38.270] Timer tick, asking Buildbucket for the build status
[20:10:38.370] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:38.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:117:0) after 1m0s
[20:11:38.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:117:0)
[20:11:38.392] Timer tick, asking Buildbucket for the build status
[20:11:38.460] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:38.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:118:0) after 1m0s
[20:12:38.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:118:0)
[20:12:38.442] Timer tick, asking Buildbucket for the build status
[20:12:38.503] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:38.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:119:0) after 1m0s
[20:13:38.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:119:0)
[20:13:38.525] Timer tick, asking Buildbucket for the build status
[20:13:38.571] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:38.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:120:0) after 1m0s
[20:14:38.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:120:0)
[20:14:38.576] Timer tick, asking Buildbucket for the build status
[20:14:38.703] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:38.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:121:0) after 1m0s
[20:15:38.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:121:0)
[20:15:38.712] Timer tick, asking Buildbucket for the build status
[20:15:39.072] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:39.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:122:0) after 1m0s
[20:16:39.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:122:0)
[20:16:39.096] Timer tick, asking Buildbucket for the build status
[20:16:39.161] Build 8915816956151067344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:39.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081247018092994512:123:0) after 1m0s
[20:16:50.200] Received PubSub notification, asking Buildbucket for the build status
[20:16:50.251] Build 8915816956151067344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:16:50.251] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555618609489292",
"created_by": "project:fuchsia",
"created_ts": "1555611381347423",
"failure_reason": "BUILD_FAILURE",
"id": "8915816956151067344",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a5dd08cac2991bbfcb304c8d4fc485381ff5f751\"}}",
"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-debian-1-ffce87c5-us-central1-b-2qh6\", \"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\": \"a5dd08cac2991bbfcb304c8d4fc485381ff5f751\", \"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-debian-1-ffce87c5-us-central1-b-2qh6\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"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\": [\"4265-bdc0466\"], \"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": "1555611387677125",
"status": "COMPLETED",
"status_changed_ts": "1555611388099110",
"tags": [
"builder:clang",
"buildset:commit/git/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5dd08cac2991bbfcb304c8d4fc485381ff5f751",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081247018092994512",
"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/8915816956151067344/+/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:444a7b7a5a0f2c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555618609557680",
"url": "https://ci.chromium.org/b/8915816956151067344",
"utcnow_ts": "1555618610242222"
}
}
[20:16:50.251] Invocation finished in 2h0m30.139740699s with status FAILED