[21:05:06.132] New invocation is queued and will start shortly
[21:05:07.263] Starting the invocation (attempt 1)
[21:05:07.303] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:05:07.303] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:05:07.303] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079152669982314224",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"605a840747be60e2813137c94ae22d27a2f74564\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079152669982314224",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/605a840747be60e2813137c94ae22d27a2f74564",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/605a840747be60e2813137c94ae22d27a2f74564",
"gitiles_ref:refs/heads/master"
]
}
[21:05:08.218] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557608707464805",
"id": "8913722607920810864",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"605a840747be60e2813137c94ae22d27a2f74564\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557608707891663",
"tags": [
"builder:clang",
"buildset:commit/git/605a840747be60e2813137c94ae22d27a2f74564",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/605a840747be60e2813137c94ae22d27a2f74564",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079152669982314224",
"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/8913722607920810864/+/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": "1557608707891768",
"url": "https://ci.chromium.org/b/8913722607920810864",
"utcnow_ts": "1557608708209671"
}
}
[21:05:08.218] Task URL: https://ci.chromium.org/b/8913722607920810864
[21:05:08.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:2:0) after 1m0s
[21:05:17.572] Received PubSub notification, asking Buildbucket for the build status
[21:05:17.692] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:08.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:2:0)
[21:06:08.238] Timer tick, asking Buildbucket for the build status
[21:06:08.320] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:08.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:4:0) after 1m0s
[21:07:08.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:4:0)
[21:07:08.364] Timer tick, asking Buildbucket for the build status
[21:07:08.526] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:08.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:5:0) after 1m0s
[21:08:08.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:5:0)
[21:08:08.543] Timer tick, asking Buildbucket for the build status
[21:08:08.595] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:6:0) after 1m0s
[21:09:08.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:6:0)
[21:09:08.616] Timer tick, asking Buildbucket for the build status
[21:09:08.684] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:08.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:7:0) after 1m0s
[21:10:08.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:7:0)
[21:10:08.700] Timer tick, asking Buildbucket for the build status
[21:10:08.844] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:08.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:8:0) after 1m0s
[21:11:08.868] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:8:0)
[21:11:08.868] Timer tick, asking Buildbucket for the build status
[21:11:09.761] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:09.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:9:0) after 1m0s
[21:12:09.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:9:0)
[21:12:09.781] Timer tick, asking Buildbucket for the build status
[21:12:09.820] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:09.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:10:0) after 1m0s
[21:13:09.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:10:0)
[21:13:09.838] Timer tick, asking Buildbucket for the build status
[21:13:10.006] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:10.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:11:0) after 1m0s
[21:14:10.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:11:0)
[21:14:10.042] Timer tick, asking Buildbucket for the build status
[21:14:10.252] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:10.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:12:0) after 1m0s
[21:15:10.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:12:0)
[21:15:10.278] Timer tick, asking Buildbucket for the build status
[21:15:10.828] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:10.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:13:0) after 1m0s
[21:16:10.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:13:0)
[21:16:10.847] Timer tick, asking Buildbucket for the build status
[21:16:10.901] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:10.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:14:0) after 1m0s
[21:17:10.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:14:0)
[21:17:10.926] Timer tick, asking Buildbucket for the build status
[21:17:11.083] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:11.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:15:0) after 1m0s
[21:18:11.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:15:0)
[21:18:11.368] Timer tick, asking Buildbucket for the build status
[21:18:11.433] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:11.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:16:0) after 1m0s
[21:19:11.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:16:0)
[21:19:11.433] Timer tick, asking Buildbucket for the build status
[21:19:11.608] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:11.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:17:0) after 1m0s
[21:20:11.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:17:0)
[21:20:11.640] Timer tick, asking Buildbucket for the build status
[21:20:11.769] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:11.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:18:0) after 1m0s
[21:21:11.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:18:0)
[21:21:11.779] Timer tick, asking Buildbucket for the build status
[21:21:11.957] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:11.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:19:0) after 1m0s
[21:22:11.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:19:0)
[21:22:11.983] Timer tick, asking Buildbucket for the build status
[21:22:12.204] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:12.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:20:0) after 1m0s
[21:23:12.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:20:0)
[21:23:12.243] Timer tick, asking Buildbucket for the build status
[21:23:12.318] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:12.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:21:0) after 1m0s
[21:24:12.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:21:0)
[21:24:12.873] Timer tick, asking Buildbucket for the build status
[21:24:12.971] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:12.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:22:0) after 1m0s
[21:25:13.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:22:0)
[21:25:13.178] Timer tick, asking Buildbucket for the build status
[21:25:13.284] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:13.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:23:0) after 1m0s
[21:26:13.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:23:0)
[21:26:13.312] Timer tick, asking Buildbucket for the build status
[21:26:13.363] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:13.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:24:0) after 1m0s
[21:27:15.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:24:0)
[21:27:15.098] Timer tick, asking Buildbucket for the build status
[21:27:15.170] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:15.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:25:0) after 1m0s
[21:28:15.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:25:0)
[21:28:15.276] Timer tick, asking Buildbucket for the build status
[21:28:15.516] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:15.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:26:0) after 1m0s
[21:29:15.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:26:0)
[21:29:15.538] Timer tick, asking Buildbucket for the build status
[21:29:15.607] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:15.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:27:0) after 1m0s
[21:30:15.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:27:0)
[21:30:15.689] Timer tick, asking Buildbucket for the build status
[21:30:15.816] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:15.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:28:0) after 1m0s
[21:31:15.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:28:0)
[21:31:15.846] Timer tick, asking Buildbucket for the build status
[21:31:16.496] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:16.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:29:0) after 1m0s
[21:32:16.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:29:0)
[21:32:16.515] Timer tick, asking Buildbucket for the build status
[21:32:16.592] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:16.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:30:0) after 1m0s
[21:33:16.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:30:0)
[21:33:16.602] Timer tick, asking Buildbucket for the build status
[21:33:16.798] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:16.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:31:0) after 1m0s
[21:34:16.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:31:0)
[21:34:16.819] Timer tick, asking Buildbucket for the build status
[21:34:16.895] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:16.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:32:0) after 1m0s
[21:35:16.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:32:0)
[21:35:16.917] Timer tick, asking Buildbucket for the build status
[21:35:16.961] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:16.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:33:0) after 1m0s
[21:36:16.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:33:0)
[21:36:16.968] Timer tick, asking Buildbucket for the build status
[21:36:17.052] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:17.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:34:0) after 1m0s
[21:37:17.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:34:0)
[21:37:17.081] Timer tick, asking Buildbucket for the build status
[21:37:17.456] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:17.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:35:0) after 1m0s
[21:38:17.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:35:0)
[21:38:17.470] Timer tick, asking Buildbucket for the build status
[21:38:17.581] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:17.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:36:0) after 1m0s
[21:39:17.631] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:36:0)
[21:39:17.631] Timer tick, asking Buildbucket for the build status
[21:39:17.700] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:17.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:37:0) after 1m0s
[21:40:17.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:37:0)
[21:40:17.716] Timer tick, asking Buildbucket for the build status
[21:40:18.440] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:18.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:38:0) after 1m0s
[21:41:18.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:38:0)
[21:41:18.437] Timer tick, asking Buildbucket for the build status
[21:41:18.495] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:18.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:39:0) after 1m0s
[21:42:18.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:39:0)
[21:42:18.476] Timer tick, asking Buildbucket for the build status
[21:42:18.574] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:18.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:40:0) after 1m0s
[21:43:18.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:40:0)
[21:43:18.597] Timer tick, asking Buildbucket for the build status
[21:43:18.681] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:18.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:41:0) after 1m0s
[21:44:18.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:41:0)
[21:44:18.700] Timer tick, asking Buildbucket for the build status
[21:44:19.062] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:19.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:42:0) after 1m0s
[21:45:19.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:42:0)
[21:45:19.099] Timer tick, asking Buildbucket for the build status
[21:45:19.242] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:19.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:43:0) after 1m0s
[21:46:19.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:43:0)
[21:46:19.268] Timer tick, asking Buildbucket for the build status
[21:46:19.364] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:19.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:44:0) after 1m0s
[21:47:19.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:44:0)
[21:47:19.336] Timer tick, asking Buildbucket for the build status
[21:47:19.428] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:19.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:45:0) after 1m0s
[21:48:19.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:45:0)
[21:48:19.486] Timer tick, asking Buildbucket for the build status
[21:48:19.962] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:19.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:46:0) after 1m0s
[21:49:19.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:46:0)
[21:49:19.963] Timer tick, asking Buildbucket for the build status
[21:49:20.048] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:20.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:47:0) after 1m0s
[21:50:20.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:47:0)
[21:50:20.079] Timer tick, asking Buildbucket for the build status
[21:50:20.796] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:20.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:48:0) after 1m0s
[21:51:20.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:48:0)
[21:51:20.875] Timer tick, asking Buildbucket for the build status
[21:51:20.962] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:20.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:49:0) after 1m0s
[21:52:20.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:49:0)
[21:52:20.980] Timer tick, asking Buildbucket for the build status
[21:52:21.103] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:21.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:50:0) after 1m0s
[21:53:21.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:50:0)
[21:53:21.128] Timer tick, asking Buildbucket for the build status
[21:53:21.193] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:21.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:51:0) after 1m0s
[21:54:21.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:51:0)
[21:54:21.211] Timer tick, asking Buildbucket for the build status
[21:54:21.623] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:21.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:52:0) after 1m0s
[21:55:21.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:52:0)
[21:55:21.636] Timer tick, asking Buildbucket for the build status
[21:55:21.707] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:21.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:53:0) after 1m0s
[21:56:21.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:53:0)
[21:56:21.679] Timer tick, asking Buildbucket for the build status
[21:56:21.752] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:21.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:54:0) after 1m0s
[21:57:21.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:54:0)
[21:57:21.786] Timer tick, asking Buildbucket for the build status
[21:57:21.844] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:21.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:55:0) after 1m0s
[21:58:21.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:55:0)
[21:58:21.867] Timer tick, asking Buildbucket for the build status
[21:58:21.980] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:21.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:56:0) after 1m0s
[21:59:22.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:56:0)
[21:59:22.129] Timer tick, asking Buildbucket for the build status
[21:59:22.178] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:22.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:57:0) after 1m0s
[22:00:22.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:57:0)
[22:00:22.205] Timer tick, asking Buildbucket for the build status
[22:00:22.298] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:22.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:58:0) after 1m0s
[22:01:22.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:58:0)
[22:01:22.307] Timer tick, asking Buildbucket for the build status
[22:01:22.523] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:22.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:59:0) after 1m0s
[22:02:22.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:59:0)
[22:02:22.543] Timer tick, asking Buildbucket for the build status
[22:02:22.889] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:22.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:60:0) after 1m0s
[22:03:22.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:60:0)
[22:03:22.910] Timer tick, asking Buildbucket for the build status
[22:03:23.382] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:23.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:61:0) after 1m0s
[22:04:23.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:61:0)
[22:04:23.410] Timer tick, asking Buildbucket for the build status
[22:04:23.508] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:23.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:62:0) after 1m0s
[22:05:23.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:62:0)
[22:05:23.535] Timer tick, asking Buildbucket for the build status
[22:05:23.799] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:23.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:63:0) after 1m0s
[22:06:23.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:63:0)
[22:06:23.827] Timer tick, asking Buildbucket for the build status
[22:06:23.961] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:23.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:64:0) after 1m0s
[22:07:23.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:64:0)
[22:07:23.993] Timer tick, asking Buildbucket for the build status
[22:07:24.128] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:24.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:65:0) after 1m0s
[22:08:24.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:65:0)
[22:08:24.128] Timer tick, asking Buildbucket for the build status
[22:08:24.193] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:24.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:66:0) after 1m0s
[22:09:24.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:66:0)
[22:09:24.197] Timer tick, asking Buildbucket for the build status
[22:09:24.486] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:24.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:67:0) after 1m0s
[22:10:24.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:67:0)
[22:10:24.515] Timer tick, asking Buildbucket for the build status
[22:10:24.813] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:24.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:68:0) after 1m0s
[22:11:24.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:68:0)
[22:11:24.833] Timer tick, asking Buildbucket for the build status
[22:11:25.328] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:25.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:69:0) after 1m0s
[22:12:25.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:69:0)
[22:12:25.304] Timer tick, asking Buildbucket for the build status
[22:12:25.639] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:25.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:70:0) after 1m0s
[22:13:25.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:70:0)
[22:13:25.665] Timer tick, asking Buildbucket for the build status
[22:13:25.876] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:25.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:71:0) after 1m0s
[22:14:25.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:71:0)
[22:14:25.903] Timer tick, asking Buildbucket for the build status
[22:14:26.092] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:26.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:72:0) after 1m0s
[22:15:26.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:72:0)
[22:15:26.113] Timer tick, asking Buildbucket for the build status
[22:15:26.533] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:26.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:73:0) after 1m0s
[22:16:26.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:73:0)
[22:16:26.567] Timer tick, asking Buildbucket for the build status
[22:16:26.725] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:26.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:74:0) after 1m0s
[22:17:26.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:74:0)
[22:17:26.767] Timer tick, asking Buildbucket for the build status
[22:17:26.842] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:26.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:75:0) after 1m0s
[22:18:26.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:75:0)
[22:18:26.876] Timer tick, asking Buildbucket for the build status
[22:18:27.251] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:27.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:76:0) after 1m0s
[22:19:27.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:76:0)
[22:19:27.380] Timer tick, asking Buildbucket for the build status
[22:19:27.542] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:27.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:77:0) after 1m0s
[22:20:27.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:77:0)
[22:20:27.532] Timer tick, asking Buildbucket for the build status
[22:20:27.625] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:27.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:78:0) after 1m0s
[22:21:27.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:78:0)
[22:21:27.619] Timer tick, asking Buildbucket for the build status
[22:21:27.727] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:27.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:79:0) after 1m0s
[22:22:27.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:79:0)
[22:22:27.732] Timer tick, asking Buildbucket for the build status
[22:22:27.780] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:27.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:80:0) after 1m0s
[22:23:27.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:80:0)
[22:23:27.826] Timer tick, asking Buildbucket for the build status
[22:23:27.971] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:27.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:81:0) after 1m0s
[22:24:28.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:81:0)
[22:24:28.225] Timer tick, asking Buildbucket for the build status
[22:24:28.344] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:28.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:82:0) after 1m0s
[22:25:31.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:82:0)
[22:25:31.351] Timer tick, asking Buildbucket for the build status
[22:25:31.539] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:31.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:83:0) after 1m0s
[22:26:31.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:83:0)
[22:26:31.560] Timer tick, asking Buildbucket for the build status
[22:26:31.694] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:31.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:84:0) after 1m0s
[22:27:31.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:84:0)
[22:27:31.716] Timer tick, asking Buildbucket for the build status
[22:27:31.756] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:31.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:85:0) after 1m0s
[22:28:31.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:85:0)
[22:28:31.735] Timer tick, asking Buildbucket for the build status
[22:28:32.401] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:32.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:86:0) after 1m0s
[22:29:32.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:86:0)
[22:29:32.457] Timer tick, asking Buildbucket for the build status
[22:29:32.518] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:32.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:87:0) after 1m0s
[22:30:32.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:87:0)
[22:30:32.539] Timer tick, asking Buildbucket for the build status
[22:30:32.712] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:32.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:88:0) after 1m0s
[22:31:32.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:88:0)
[22:31:32.755] Timer tick, asking Buildbucket for the build status
[22:31:33.081] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:33.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:89:0) after 1m0s
[22:32:33.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:89:0)
[22:32:33.101] Timer tick, asking Buildbucket for the build status
[22:32:33.156] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:33.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:90:0) after 1m0s
[22:33:33.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:90:0)
[22:33:33.186] Timer tick, asking Buildbucket for the build status
[22:33:33.855] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:33.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:91:0) after 1m0s
[22:34:34.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:91:0)
[22:34:34.466] Timer tick, asking Buildbucket for the build status
[22:34:34.503] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:34.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:92:0) after 1m0s
[22:35:34.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:92:0)
[22:35:34.509] Timer tick, asking Buildbucket for the build status
[22:35:34.675] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:34.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:93:0) after 1m0s
[22:36:34.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:93:0)
[22:36:34.679] Timer tick, asking Buildbucket for the build status
[22:36:34.835] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:34.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:94:0) after 1m0s
[22:37:34.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:94:0)
[22:37:34.870] Timer tick, asking Buildbucket for the build status
[22:37:34.983] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:34.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:95:0) after 1m0s
[22:38:34.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:95:0)
[22:38:34.970] Timer tick, asking Buildbucket for the build status
[22:38:35.059] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:35.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:96:0) after 1m0s
[22:39:35.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:96:0)
[22:39:35.096] Timer tick, asking Buildbucket for the build status
[22:39:35.198] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:35.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:97:0) after 1m0s
[22:40:35.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:97:0)
[22:40:35.247] Timer tick, asking Buildbucket for the build status
[22:40:35.388] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:35.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:98:0) after 1m0s
[22:41:35.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:98:0)
[22:41:35.423] Timer tick, asking Buildbucket for the build status
[22:41:35.583] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:35.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:99:0) after 1m0s
[22:42:35.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:99:0)
[22:42:35.611] Timer tick, asking Buildbucket for the build status
[22:42:36.418] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:36.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:100:0) after 1m0s
[22:43:36.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:100:0)
[22:43:36.531] Timer tick, asking Buildbucket for the build status
[22:43:36.754] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:36.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:101:0) after 1m0s
[22:44:36.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:101:0)
[22:44:36.926] Timer tick, asking Buildbucket for the build status
[22:44:37.252] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:37.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:102:0) after 1m0s
[22:45:37.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:102:0)
[22:45:37.515] Timer tick, asking Buildbucket for the build status
[22:45:37.695] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:37.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:103:0) after 1m0s
[22:46:37.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:103:0)
[22:46:37.730] Timer tick, asking Buildbucket for the build status
[22:46:37.806] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:104:0) after 1m0s
[22:47:37.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:104:0)
[22:47:37.902] Timer tick, asking Buildbucket for the build status
[22:47:37.947] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:105:0) after 1m0s
[22:48:37.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:105:0)
[22:48:37.955] Timer tick, asking Buildbucket for the build status
[22:48:38.017] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:106:0) after 1m0s
[22:49:37.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:106:0)
[22:49:37.998] Timer tick, asking Buildbucket for the build status
[22:49:38.044] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:107:0) after 1m0s
[22:50:38.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:107:0)
[22:50:38.066] Timer tick, asking Buildbucket for the build status
[22:50:38.117] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:108:0) after 1m0s
[22:51:41.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:108:0)
[22:51:41.499] Timer tick, asking Buildbucket for the build status
[22:51:41.892] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:41.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:109:0) after 1m0s
[22:52:41.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:109:0)
[22:52:41.920] Timer tick, asking Buildbucket for the build status
[22:52:42.006] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:42.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:110:0) after 1m0s
[22:53:42.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:110:0)
[22:53:42.045] Timer tick, asking Buildbucket for the build status
[22:53:42.149] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:42.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:111:0) after 1m0s
[22:54:42.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:111:0)
[22:54:42.152] Timer tick, asking Buildbucket for the build status
[22:54:42.298] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:42.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:112:0) after 1m0s
[22:55:42.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:112:0)
[22:55:42.332] Timer tick, asking Buildbucket for the build status
[22:55:42.407] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:42.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:113:0) after 1m0s
[22:56:42.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:113:0)
[22:56:42.434] Timer tick, asking Buildbucket for the build status
[22:56:42.494] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:42.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:114:0) after 1m0s
[22:57:42.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:114:0)
[22:57:42.530] Timer tick, asking Buildbucket for the build status
[22:57:42.682] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:42.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:115:0) after 1m0s
[22:58:42.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:115:0)
[22:58:42.743] Timer tick, asking Buildbucket for the build status
[22:58:42.857] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:42.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:116:0) after 1m0s
[22:59:42.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:116:0)
[22:59:42.895] Timer tick, asking Buildbucket for the build status
[22:59:42.982] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:42.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:117:0) after 1m0s
[23:00:43.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:117:0)
[23:00:43.078] Timer tick, asking Buildbucket for the build status
[23:00:43.181] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:43.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:118:0) after 1m0s
[23:01:43.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:118:0)
[23:01:43.217] Timer tick, asking Buildbucket for the build status
[23:01:43.592] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:43.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:119:0) after 1m0s
[23:02:43.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:119:0)
[23:02:43.619] Timer tick, asking Buildbucket for the build status
[23:02:43.788] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:43.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:120:0) after 1m0s
[23:03:43.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:120:0)
[23:03:43.834] Timer tick, asking Buildbucket for the build status
[23:03:43.894] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:43.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:121:0) after 1m0s
[23:04:43.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:121:0)
[23:04:43.931] Timer tick, asking Buildbucket for the build status
[23:04:44.103] Build 8913722607920810864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079152669982314224:122:0) after 1m0s
[23:05:40.521] Received PubSub notification, asking Buildbucket for the build status
[23:05:40.569] Build 8913722607920810864: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:05:40.569] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557615939835424",
"created_by": "project:fuchsia",
"created_ts": "1557608707464805",
"failure_reason": "BUILD_FAILURE",
"id": "8913722607920810864",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"605a840747be60e2813137c94ae22d27a2f74564\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-1-d1n6\", \"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\": \"605a840747be60e2813137c94ae22d27a2f74564\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-d1n6\"], \"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\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1557608716605509",
"status": "COMPLETED",
"status_changed_ts": "1557608717105510",
"tags": [
"builder:clang",
"buildset:commit/git/605a840747be60e2813137c94ae22d27a2f74564",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/605a840747be60e2813137c94ae22d27a2f74564",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079152669982314224",
"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/8913722607920810864/+/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:44c18842e0536610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557615939951906",
"url": "https://ci.chromium.org/b/8913722607920810864",
"utcnow_ts": "1557615940557121"
}
}
[23:05:40.569] Invocation finished in 2h0m34.454647846s with status FAILED