[00:14:40.477] New invocation is queued and will start shortly
[00:14:41.556] Starting the invocation (attempt 1)
[00:14:41.605] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:14:41.606] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:14:41.606] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083308203569831584",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"818698010cd47b6ed76b58f06eb9ac62045eb0aa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083308203569831584",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"gitiles_ref:refs/heads/master"
]
}
[00:14:42.284] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553645681730858",
"id": "8917878141592708400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"818698010cd47b6ed76b58f06eb9ac62045eb0aa\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553645682031952",
"tags": [
"builder:clang",
"buildset:commit/git/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083308203569831584",
"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/8917878141592708400/+/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": "1553645682032047",
"url": "https://ci.chromium.org/b/8917878141592708400",
"utcnow_ts": "1553645682256216"
}
}
[00:14:42.284] Task URL: https://ci.chromium.org/b/8917878141592708400
[00:14:42.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:2:0) after 1m0s
[00:15:11.906] Received PubSub notification, asking Buildbucket for the build status
[00:15:11.958] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:42.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:2:0)
[00:15:42.292] Timer tick, asking Buildbucket for the build status
[00:15:42.575] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:42.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:4:0) after 1m0s
[00:16:42.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:4:0)
[00:16:42.609] Timer tick, asking Buildbucket for the build status
[00:16:42.708] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:42.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:5:0) after 1m0s
[00:17:42.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:5:0)
[00:17:42.713] Timer tick, asking Buildbucket for the build status
[00:17:42.763] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:42.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:6:0) after 1m0s
[00:18:42.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:6:0)
[00:18:42.782] Timer tick, asking Buildbucket for the build status
[00:18:42.866] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:42.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:7:0) after 1m0s
[00:19:42.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:7:0)
[00:19:42.883] Timer tick, asking Buildbucket for the build status
[00:19:42.999] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:42.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:8:0) after 1m0s
[00:20:42.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:8:0)
[00:20:42.983] Timer tick, asking Buildbucket for the build status
[00:20:43.107] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:43.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:9:0) after 1m0s
[00:21:43.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:9:0)
[00:21:43.115] Timer tick, asking Buildbucket for the build status
[00:21:43.286] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:43.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:10:0) after 1m0s
[00:22:43.284] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:10:0)
[00:22:43.284] Timer tick, asking Buildbucket for the build status
[00:22:43.340] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:43.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:11:0) after 1m0s
[00:23:43.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:11:0)
[00:23:43.358] Timer tick, asking Buildbucket for the build status
[00:23:43.480] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:43.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:12:0) after 1m0s
[00:24:43.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:12:0)
[00:24:43.489] Timer tick, asking Buildbucket for the build status
[00:24:43.534] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:43.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:13:0) after 1m0s
[00:25:43.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:13:0)
[00:25:43.521] Timer tick, asking Buildbucket for the build status
[00:25:43.624] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:43.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:14:0) after 1m0s
[00:26:43.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:14:0)
[00:26:43.643] Timer tick, asking Buildbucket for the build status
[00:26:43.716] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:43.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:15:0) after 1m0s
[00:27:43.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:15:0)
[00:27:43.737] Timer tick, asking Buildbucket for the build status
[00:27:43.829] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:43.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:16:0) after 1m0s
[00:28:43.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:16:0)
[00:28:43.851] Timer tick, asking Buildbucket for the build status
[00:28:43.979] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:43.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:17:0) after 1m0s
[00:29:44.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:17:0)
[00:29:44.001] Timer tick, asking Buildbucket for the build status
[00:29:44.296] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:44.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:18:0) after 1m0s
[00:30:44.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:18:0)
[00:30:44.320] Timer tick, asking Buildbucket for the build status
[00:30:44.374] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:44.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:19:0) after 1m0s
[00:31:44.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:19:0)
[00:31:44.396] Timer tick, asking Buildbucket for the build status
[00:31:44.478] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:44.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:20:0) after 1m0s
[00:32:44.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:20:0)
[00:32:44.499] Timer tick, asking Buildbucket for the build status
[00:32:44.668] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:44.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:21:0) after 1m0s
[00:33:44.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:21:0)
[00:33:44.699] Timer tick, asking Buildbucket for the build status
[00:33:45.058] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:45.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:22:0) after 1m0s
[00:34:45.055] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:22:0)
[00:34:45.055] Timer tick, asking Buildbucket for the build status
[00:34:45.122] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:45.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:23:0) after 1m0s
[00:35:45.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:23:0)
[00:35:45.100] Timer tick, asking Buildbucket for the build status
[00:35:45.232] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:45.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:24:0) after 1m0s
[00:36:45.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:24:0)
[00:36:45.225] Timer tick, asking Buildbucket for the build status
[00:36:45.269] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:45.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:25:0) after 1m0s
[00:37:45.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:25:0)
[00:37:45.302] Timer tick, asking Buildbucket for the build status
[00:37:45.480] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:45.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:26:0) after 1m0s
[00:38:45.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:26:0)
[00:38:45.508] Timer tick, asking Buildbucket for the build status
[00:38:45.619] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:45.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:27:0) after 1m0s
[00:39:45.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:27:0)
[00:39:45.639] Timer tick, asking Buildbucket for the build status
[00:39:45.707] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:45.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:28:0) after 1m0s
[00:40:45.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:28:0)
[00:40:45.732] Timer tick, asking Buildbucket for the build status
[00:40:46.154] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:46.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:29:0) after 1m0s
[00:41:46.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:29:0)
[00:41:46.177] Timer tick, asking Buildbucket for the build status
[00:41:46.248] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:46.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:30:0) after 1m0s
[00:42:46.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:30:0)
[00:42:46.268] Timer tick, asking Buildbucket for the build status
[00:42:46.321] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:46.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:31:0) after 1m0s
[00:43:46.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:31:0)
[00:43:46.353] Timer tick, asking Buildbucket for the build status
[00:43:46.498] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:46.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:32:0) after 1m0s
[00:44:46.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:32:0)
[00:44:46.516] Timer tick, asking Buildbucket for the build status
[00:44:46.578] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:46.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:33:0) after 1m0s
[00:45:46.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:33:0)
[00:45:46.576] Timer tick, asking Buildbucket for the build status
[00:45:46.653] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:46.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:34:0) after 1m0s
[00:46:46.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:34:0)
[00:46:46.683] Timer tick, asking Buildbucket for the build status
[00:46:46.802] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:46.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:35:0) after 1m0s
[00:47:46.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:35:0)
[00:47:46.805] Timer tick, asking Buildbucket for the build status
[00:47:49.183] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:49.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:36:0) after 1m0s
[00:48:49.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:36:0)
[00:48:49.207] Timer tick, asking Buildbucket for the build status
[00:48:49.359] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:49.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:37:0) after 1m0s
[00:49:49.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:37:0)
[00:49:49.348] Timer tick, asking Buildbucket for the build status
[00:49:49.414] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:49.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:38:0) after 1m0s
[00:50:49.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:38:0)
[00:50:49.434] Timer tick, asking Buildbucket for the build status
[00:50:49.503] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:49.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:39:0) after 1m0s
[00:51:49.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:39:0)
[00:51:49.492] Timer tick, asking Buildbucket for the build status
[00:51:49.540] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:49.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:40:0) after 1m0s
[00:52:49.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:40:0)
[00:52:49.558] Timer tick, asking Buildbucket for the build status
[00:52:49.710] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:49.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:41:0) after 1m0s
[00:53:49.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:41:0)
[00:53:49.769] Timer tick, asking Buildbucket for the build status
[00:53:49.965] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:49.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:42:0) after 1m0s
[00:54:49.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:42:0)
[00:54:49.985] Timer tick, asking Buildbucket for the build status
[00:54:50.171] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:50.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:43:0) after 1m0s
[00:55:50.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:43:0)
[00:55:50.192] Timer tick, asking Buildbucket for the build status
[00:55:50.291] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:50.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:44:0) after 1m0s
[00:56:50.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:44:0)
[00:56:50.320] Timer tick, asking Buildbucket for the build status
[00:56:50.380] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:50.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:45:0) after 1m0s
[00:57:50.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:45:0)
[00:57:50.404] Timer tick, asking Buildbucket for the build status
[00:57:50.489] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:50.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:46:0) after 1m0s
[00:58:50.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:46:0)
[00:58:50.512] Timer tick, asking Buildbucket for the build status
[00:58:50.653] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:50.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:47:0) after 1m0s
[00:59:50.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:47:0)
[00:59:50.685] Timer tick, asking Buildbucket for the build status
[00:59:50.803] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:50.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:48:0) after 1m0s
[01:00:50.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:48:0)
[01:00:50.828] Timer tick, asking Buildbucket for the build status
[01:00:50.906] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:50.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:49:0) after 1m0s
[01:01:50.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:49:0)
[01:01:50.953] Timer tick, asking Buildbucket for the build status
[01:01:51.057] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:51.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:50:0) after 1m0s
[01:02:51.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:50:0)
[01:02:51.077] Timer tick, asking Buildbucket for the build status
[01:02:51.137] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:51.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:51:0) after 1m0s
[01:03:51.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:51:0)
[01:03:51.158] Timer tick, asking Buildbucket for the build status
[01:03:51.211] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:51.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:52:0) after 1m0s
[01:04:51.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:52:0)
[01:04:51.239] Timer tick, asking Buildbucket for the build status
[01:04:51.551] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:51.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:53:0) after 1m0s
[01:05:51.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:53:0)
[01:05:51.575] Timer tick, asking Buildbucket for the build status
[01:05:51.667] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:51.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:54:0) after 1m0s
[01:06:51.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:54:0)
[01:06:51.659] Timer tick, asking Buildbucket for the build status
[01:06:51.735] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:51.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:55:0) after 1m0s
[01:07:51.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:55:0)
[01:07:51.728] Timer tick, asking Buildbucket for the build status
[01:07:51.779] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:51.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:56:0) after 1m0s
[01:08:51.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:56:0)
[01:08:51.802] Timer tick, asking Buildbucket for the build status
[01:08:51.871] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:51.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:57:0) after 1m0s
[01:09:51.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:57:0)
[01:09:51.904] Timer tick, asking Buildbucket for the build status
[01:09:52.001] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:52.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:58:0) after 1m0s
[01:10:51.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:58:0)
[01:10:51.995] Timer tick, asking Buildbucket for the build status
[01:10:52.087] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:52.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:59:0) after 1m0s
[01:11:52.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:59:0)
[01:11:52.132] Timer tick, asking Buildbucket for the build status
[01:11:52.276] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:52.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:60:0) after 1m0s
[01:12:52.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:60:0)
[01:12:52.296] Timer tick, asking Buildbucket for the build status
[01:12:52.823] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:52.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:61:0) after 1m0s
[01:13:52.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:61:0)
[01:13:52.845] Timer tick, asking Buildbucket for the build status
[01:13:52.910] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:52.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:62:0) after 1m0s
[01:14:52.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:62:0)
[01:14:52.911] Timer tick, asking Buildbucket for the build status
[01:14:53.079] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:53.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:63:0) after 1m0s
[01:15:53.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:63:0)
[01:15:53.109] Timer tick, asking Buildbucket for the build status
[01:15:53.172] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:53.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:64:0) after 1m0s
[01:16:53.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:64:0)
[01:16:53.193] Timer tick, asking Buildbucket for the build status
[01:16:53.256] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:53.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:65:0) after 1m0s
[01:17:53.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:65:0)
[01:17:53.276] Timer tick, asking Buildbucket for the build status
[01:17:53.362] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:53.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:66:0) after 1m0s
[01:18:53.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:66:0)
[01:18:53.384] Timer tick, asking Buildbucket for the build status
[01:18:53.474] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:53.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:67:0) after 1m0s
[01:19:53.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:67:0)
[01:19:53.497] Timer tick, asking Buildbucket for the build status
[01:19:53.562] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:53.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:68:0) after 1m0s
[01:20:53.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:68:0)
[01:20:53.584] Timer tick, asking Buildbucket for the build status
[01:20:53.648] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:53.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:69:0) after 1m0s
[01:21:53.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:69:0)
[01:21:53.695] Timer tick, asking Buildbucket for the build status
[01:21:53.787] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:53.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:70:0) after 1m0s
[01:22:53.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:70:0)
[01:22:53.777] Timer tick, asking Buildbucket for the build status
[01:22:53.855] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:53.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:71:0) after 1m0s
[01:23:53.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:71:0)
[01:23:53.875] Timer tick, asking Buildbucket for the build status
[01:23:53.997] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:53.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:72:0) after 1m0s
[01:24:54.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:72:0)
[01:24:54.004] Timer tick, asking Buildbucket for the build status
[01:24:54.082] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:54.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:73:0) after 1m0s
[01:25:54.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:73:0)
[01:25:54.106] Timer tick, asking Buildbucket for the build status
[01:25:54.360] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:54.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:74:0) after 1m0s
[01:26:54.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:74:0)
[01:26:54.390] Timer tick, asking Buildbucket for the build status
[01:26:54.482] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:54.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:75:0) after 1m0s
[01:27:54.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:75:0)
[01:27:54.502] Timer tick, asking Buildbucket for the build status
[01:27:54.563] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:54.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:76:0) after 1m0s
[01:28:54.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:76:0)
[01:28:54.574] Timer tick, asking Buildbucket for the build status
[01:28:55.493] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:55.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:77:0) after 1m0s
[01:29:55.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:77:0)
[01:29:55.516] Timer tick, asking Buildbucket for the build status
[01:29:55.624] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:55.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:78:0) after 1m0s
[01:30:55.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:78:0)
[01:30:55.646] Timer tick, asking Buildbucket for the build status
[01:30:55.736] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:55.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:79:0) after 1m0s
[01:31:55.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:79:0)
[01:31:55.764] Timer tick, asking Buildbucket for the build status
[01:31:55.813] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:55.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:80:0) after 1m0s
[01:32:55.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:80:0)
[01:32:55.851] Timer tick, asking Buildbucket for the build status
[01:32:55.916] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:55.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:81:0) after 1m0s
[01:33:55.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:81:0)
[01:33:55.937] Timer tick, asking Buildbucket for the build status
[01:33:55.999] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:55.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:82:0) after 1m0s
[01:34:55.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:82:0)
[01:34:55.971] Timer tick, asking Buildbucket for the build status
[01:34:56.160] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:56.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:83:0) after 1m0s
[01:35:56.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:83:0)
[01:35:56.177] Timer tick, asking Buildbucket for the build status
[01:35:56.233] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:56.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:84:0) after 1m0s
[01:36:56.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:84:0)
[01:36:56.254] Timer tick, asking Buildbucket for the build status
[01:36:56.388] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:56.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:85:0) after 1m0s
[01:37:56.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:85:0)
[01:37:56.371] Timer tick, asking Buildbucket for the build status
[01:37:56.433] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:56.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:86:0) after 1m0s
[01:38:56.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:86:0)
[01:38:56.456] Timer tick, asking Buildbucket for the build status
[01:38:56.607] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:56.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:87:0) after 1m0s
[01:39:56.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:87:0)
[01:39:56.633] Timer tick, asking Buildbucket for the build status
[01:39:56.760] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:56.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:88:0) after 1m0s
[01:40:56.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:88:0)
[01:40:56.747] Timer tick, asking Buildbucket for the build status
[01:40:56.817] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:56.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:89:0) after 1m0s
[01:41:56.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:89:0)
[01:41:56.836] Timer tick, asking Buildbucket for the build status
[01:41:56.891] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:56.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:90:0) after 1m0s
[01:42:56.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:90:0)
[01:42:56.916] Timer tick, asking Buildbucket for the build status
[01:42:57.125] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:57.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:91:0) after 1m0s
[01:43:57.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:91:0)
[01:43:57.144] Timer tick, asking Buildbucket for the build status
[01:43:57.204] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:57.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:92:0) after 1m0s
[01:44:57.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:92:0)
[01:44:57.230] Timer tick, asking Buildbucket for the build status
[01:44:57.275] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:57.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:93:0) after 1m0s
[01:45:57.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:93:0)
[01:45:57.299] Timer tick, asking Buildbucket for the build status
[01:45:57.371] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:57.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:94:0) after 1m0s
[01:46:57.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:94:0)
[01:46:57.345] Timer tick, asking Buildbucket for the build status
[01:46:57.418] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:57.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:95:0) after 1m0s
[01:47:57.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:95:0)
[01:47:57.420] Timer tick, asking Buildbucket for the build status
[01:47:57.462] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:57.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:96:0) after 1m0s
[01:48:57.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:96:0)
[01:48:57.501] Timer tick, asking Buildbucket for the build status
[01:48:57.581] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:57.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:97:0) after 1m0s
[01:49:57.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:97:0)
[01:49:57.595] Timer tick, asking Buildbucket for the build status
[01:49:57.636] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:57.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:98:0) after 1m0s
[01:50:57.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:98:0)
[01:50:57.656] Timer tick, asking Buildbucket for the build status
[01:50:57.949] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:57.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:99:0) after 1m0s
[01:51:57.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:99:0)
[01:51:57.966] Timer tick, asking Buildbucket for the build status
[01:51:58.042] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:58.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:100:0) after 1m0s
[01:52:58.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:100:0)
[01:52:58.071] Timer tick, asking Buildbucket for the build status
[01:52:58.203] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:58.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:101:0) after 1m0s
[01:53:58.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:101:0)
[01:53:58.223] Timer tick, asking Buildbucket for the build status
[01:53:58.389] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:58.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:102:0) after 1m0s
[01:54:58.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:102:0)
[01:54:58.466] Timer tick, asking Buildbucket for the build status
[01:54:58.693] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:58.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:103:0) after 1m0s
[01:55:58.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:103:0)
[01:55:58.678] Timer tick, asking Buildbucket for the build status
[01:55:58.744] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:58.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:104:0) after 1m0s
[01:56:58.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:104:0)
[01:56:58.764] Timer tick, asking Buildbucket for the build status
[01:56:58.828] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:58.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:105:0) after 1m0s
[01:57:58.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:105:0)
[01:57:58.863] Timer tick, asking Buildbucket for the build status
[01:57:58.975] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:58.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:106:0) after 1m0s
[01:58:58.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:106:0)
[01:58:58.997] Timer tick, asking Buildbucket for the build status
[01:58:59.055] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:59.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:107:0) after 1m0s
[01:59:59.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:107:0)
[01:59:59.065] Timer tick, asking Buildbucket for the build status
[01:59:59.299] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:59.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:108:0) after 1m0s
[02:00:59.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:108:0)
[02:00:59.338] Timer tick, asking Buildbucket for the build status
[02:00:59.404] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:59.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:109:0) after 1m0s
[02:01:59.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:109:0)
[02:01:59.425] Timer tick, asking Buildbucket for the build status
[02:01:59.523] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:110:0) after 1m0s
[02:02:59.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:110:0)
[02:02:59.550] Timer tick, asking Buildbucket for the build status
[02:02:59.593] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:111:0) after 1m0s
[02:03:59.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:111:0)
[02:03:59.615] Timer tick, asking Buildbucket for the build status
[02:03:59.667] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:59.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:112:0) after 1m0s
[02:04:59.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:112:0)
[02:04:59.692] Timer tick, asking Buildbucket for the build status
[02:04:59.733] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:59.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:113:0) after 1m0s
[02:05:59.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:113:0)
[02:05:59.755] Timer tick, asking Buildbucket for the build status
[02:05:59.816] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:59.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:114:0) after 1m0s
[02:06:59.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:114:0)
[02:06:59.837] Timer tick, asking Buildbucket for the build status
[02:06:59.935] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:59.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:115:0) after 1m0s
[02:07:59.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:115:0)
[02:07:59.956] Timer tick, asking Buildbucket for the build status
[02:08:00.017] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:00.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:116:0) after 1m0s
[02:09:00.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:116:0)
[02:09:00.045] Timer tick, asking Buildbucket for the build status
[02:09:00.091] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:00.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:117:0) after 1m0s
[02:10:00.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:117:0)
[02:10:00.111] Timer tick, asking Buildbucket for the build status
[02:10:00.288] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:118:0) after 1m0s
[02:11:00.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:118:0)
[02:11:00.307] Timer tick, asking Buildbucket for the build status
[02:11:00.375] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:00.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:119:0) after 1m0s
[02:12:00.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:119:0)
[02:12:00.402] Timer tick, asking Buildbucket for the build status
[02:12:00.455] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:00.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:120:0) after 1m0s
[02:13:00.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:120:0)
[02:13:00.481] Timer tick, asking Buildbucket for the build status
[02:13:00.542] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:00.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:121:0) after 1m0s
[02:14:00.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:121:0)
[02:14:00.557] Timer tick, asking Buildbucket for the build status
[02:14:00.817] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:00.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:122:0) after 1m0s
[02:15:00.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:122:0)
[02:15:00.827] Timer tick, asking Buildbucket for the build status
[02:15:00.869] Build 8917878141592708400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:00.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083308203569831584:123:0) after 1m0s
[02:15:39.148] Received PubSub notification, asking Buildbucket for the build status
[02:15:39.263] Build 8917878141592708400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:15:39.263] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553652938842656",
"created_by": "project:fuchsia",
"created_ts": "1553645681730858",
"failure_reason": "BUILD_FAILURE",
"id": "8917878141592708400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"818698010cd47b6ed76b58f06eb9ac62045eb0aa\"}}",
"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-7887e815-us-central1-c-tl2q\", \"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\": \"818698010cd47b6ed76b58f06eb9ac62045eb0aa\", \"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-7887e815-us-central1-c-tl2q\"], \"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\": [\"4221-9c50278\"], \"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": "1553645711131808",
"status": "COMPLETED",
"status_changed_ts": "1553645711503642",
"tags": [
"builder:clang",
"buildset:commit/git/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/818698010cd47b6ed76b58f06eb9ac62045eb0aa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083308203569831584",
"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/8917878141592708400/+/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:43d5514742f57f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553652938893076",
"url": "https://ci.chromium.org/b/8917878141592708400",
"utcnow_ts": "1553652939246381"
}
}
[02:15:39.263] Invocation finished in 2h0m58.802227701s with status FAILED