[06:52:01.323] New invocation is queued and will start shortly
[06:52:02.403] Starting the invocation (attempt 1)
[06:52:02.436] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:52:02.436] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:52:02.436] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079387535077695280",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"366cda03a89c7a03fa0c83003ba4bca42d284230\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079387535077695280",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/366cda03a89c7a03fa0c83003ba4bca42d284230",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/366cda03a89c7a03fa0c83003ba4bca42d284230",
"gitiles_ref:refs/heads/master"
]
}
[06:52:06.726] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557384722975333",
"id": "8913957472680457344",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"366cda03a89c7a03fa0c83003ba4bca42d284230\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557384726059786",
"tags": [
"builder:clang",
"buildset:commit/git/366cda03a89c7a03fa0c83003ba4bca42d284230",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/366cda03a89c7a03fa0c83003ba4bca42d284230",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079387535077695280",
"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/8913957472680457344/+/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": "1557384726059940",
"url": "https://ci.chromium.org/b/8913957472680457344",
"utcnow_ts": "1557384726696693"
}
}
[06:52:06.726] Task URL: https://ci.chromium.org/b/8913957472680457344
[06:52:06.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:2:0) after 1m0s
[06:52:12.913] Received PubSub notification, asking Buildbucket for the build status
[06:52:13.026] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:2:0)
[06:53:06.751] Timer tick, asking Buildbucket for the build status
[06:53:06.883] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:4:0) after 1m0s
[06:54:06.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:4:0)
[06:54:06.955] Timer tick, asking Buildbucket for the build status
[06:54:07.005] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:07.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:5:0) after 1m0s
[06:55:07.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:5:0)
[06:55:07.026] Timer tick, asking Buildbucket for the build status
[06:55:07.131] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:07.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:6:0) after 1m0s
[06:56:07.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:6:0)
[06:56:07.148] Timer tick, asking Buildbucket for the build status
[06:56:07.996] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:07.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:7:0) after 1m0s
[06:57:08.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:7:0)
[06:57:08.001] Timer tick, asking Buildbucket for the build status
[06:57:08.136] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:08.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:8:0) after 1m0s
[06:58:08.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:8:0)
[06:58:08.158] Timer tick, asking Buildbucket for the build status
[06:58:08.450] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:08.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:9:0) after 1m0s
[06:59:08.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:9:0)
[06:59:08.478] Timer tick, asking Buildbucket for the build status
[06:59:08.569] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:08.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:10:0) after 1m0s
[07:00:08.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:10:0)
[07:00:08.589] Timer tick, asking Buildbucket for the build status
[07:00:10.251] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:10.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:11:0) after 1m0s
[07:01:10.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:11:0)
[07:01:10.240] Timer tick, asking Buildbucket for the build status
[07:01:10.709] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:10.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:12:0) after 1m0s
[07:02:10.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:12:0)
[07:02:10.726] Timer tick, asking Buildbucket for the build status
[07:02:11.055] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:11.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:13:0) after 1m0s
[07:03:11.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:13:0)
[07:03:11.075] Timer tick, asking Buildbucket for the build status
[07:03:11.183] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:14:0) after 1m0s
[07:04:11.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:14:0)
[07:04:11.204] Timer tick, asking Buildbucket for the build status
[07:04:11.249] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:11.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:15:0) after 1m0s
[07:05:11.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:15:0)
[07:05:11.268] Timer tick, asking Buildbucket for the build status
[07:05:11.306] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:11.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:16:0) after 1m0s
[07:06:11.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:16:0)
[07:06:11.323] Timer tick, asking Buildbucket for the build status
[07:06:11.383] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:11.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:17:0) after 1m0s
[07:07:11.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:17:0)
[07:07:11.396] Timer tick, asking Buildbucket for the build status
[07:07:11.450] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:11.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:18:0) after 1m0s
[07:08:11.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:18:0)
[07:08:11.469] Timer tick, asking Buildbucket for the build status
[07:08:11.533] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:11.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:19:0) after 1m0s
[07:09:11.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:19:0)
[07:09:11.556] Timer tick, asking Buildbucket for the build status
[07:09:11.602] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:11.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:20:0) after 1m0s
[07:10:11.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:20:0)
[07:10:11.622] Timer tick, asking Buildbucket for the build status
[07:10:11.689] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:11.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:21:0) after 1m0s
[07:11:11.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:21:0)
[07:11:11.704] Timer tick, asking Buildbucket for the build status
[07:11:11.768] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:11.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:22:0) after 1m0s
[07:12:11.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:22:0)
[07:12:11.821] Timer tick, asking Buildbucket for the build status
[07:12:11.865] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:11.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:23:0) after 1m0s
[07:13:11.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:23:0)
[07:13:11.878] Timer tick, asking Buildbucket for the build status
[07:13:11.948] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:11.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:24:0) after 1m0s
[07:14:11.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:24:0)
[07:14:11.955] Timer tick, asking Buildbucket for the build status
[07:14:12.010] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:12.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:25:0) after 1m0s
[07:15:11.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:25:0)
[07:15:11.999] Timer tick, asking Buildbucket for the build status
[07:15:12.190] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:12.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:26:0) after 1m0s
[07:16:12.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:26:0)
[07:16:12.213] Timer tick, asking Buildbucket for the build status
[07:16:12.275] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:12.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:27:0) after 1m0s
[07:17:12.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:27:0)
[07:17:12.266] Timer tick, asking Buildbucket for the build status
[07:17:12.327] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:12.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:28:0) after 1m0s
[07:18:12.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:28:0)
[07:18:12.345] Timer tick, asking Buildbucket for the build status
[07:18:12.480] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:12.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:29:0) after 1m0s
[07:19:12.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:29:0)
[07:19:12.502] Timer tick, asking Buildbucket for the build status
[07:19:12.550] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:12.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:30:0) after 1m0s
[07:20:12.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:30:0)
[07:20:12.569] Timer tick, asking Buildbucket for the build status
[07:20:12.845] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:12.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:31:0) after 1m0s
[07:21:12.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:31:0)
[07:21:12.888] Timer tick, asking Buildbucket for the build status
[07:21:12.951] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:12.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:32:0) after 1m0s
[07:22:12.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:32:0)
[07:22:12.985] Timer tick, asking Buildbucket for the build status
[07:22:13.222] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:13.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:33:0) after 1m0s
[07:23:13.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:33:0)
[07:23:13.234] Timer tick, asking Buildbucket for the build status
[07:23:13.360] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:13.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:34:0) after 1m0s
[07:24:13.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:34:0)
[07:24:13.341] Timer tick, asking Buildbucket for the build status
[07:24:13.666] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:13.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:35:0) after 1m0s
[07:25:13.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:35:0)
[07:25:13.659] Timer tick, asking Buildbucket for the build status
[07:25:13.727] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:13.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:36:0) after 1m0s
[07:26:13.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:36:0)
[07:26:13.750] Timer tick, asking Buildbucket for the build status
[07:26:13.974] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:13.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:37:0) after 1m0s
[07:27:13.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:37:0)
[07:27:13.976] Timer tick, asking Buildbucket for the build status
[07:27:14.083] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:14.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:38:0) after 1m0s
[07:28:14.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:38:0)
[07:28:14.123] Timer tick, asking Buildbucket for the build status
[07:28:14.367] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:14.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:39:0) after 1m0s
[07:29:14.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:39:0)
[07:29:14.389] Timer tick, asking Buildbucket for the build status
[07:29:14.676] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:14.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:40:0) after 1m0s
[07:30:14.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:40:0)
[07:30:14.700] Timer tick, asking Buildbucket for the build status
[07:30:14.931] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:14.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:41:0) after 1m0s
[07:31:14.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:41:0)
[07:31:14.969] Timer tick, asking Buildbucket for the build status
[07:31:15.017] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:15.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:42:0) after 1m0s
[07:32:15.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:42:0)
[07:32:15.053] Timer tick, asking Buildbucket for the build status
[07:32:15.148] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:15.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:43:0) after 1m0s
[07:33:15.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:43:0)
[07:33:15.133] Timer tick, asking Buildbucket for the build status
[07:33:15.189] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:15.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:44:0) after 1m0s
[07:34:15.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:44:0)
[07:34:15.209] Timer tick, asking Buildbucket for the build status
[07:34:15.347] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:15.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:45:0) after 1m0s
[07:35:15.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:45:0)
[07:35:15.378] Timer tick, asking Buildbucket for the build status
[07:35:15.812] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:15.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:46:0) after 1m0s
[07:36:15.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:46:0)
[07:36:15.836] Timer tick, asking Buildbucket for the build status
[07:36:15.931] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:15.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:47:0) after 1m0s
[07:37:15.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:47:0)
[07:37:15.954] Timer tick, asking Buildbucket for the build status
[07:37:16.177] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:16.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:48:0) after 1m0s
[07:38:16.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:48:0)
[07:38:16.199] Timer tick, asking Buildbucket for the build status
[07:38:16.271] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:16.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:49:0) after 1m0s
[07:39:16.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:49:0)
[07:39:16.264] Timer tick, asking Buildbucket for the build status
[07:39:16.474] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:16.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:50:0) after 1m0s
[07:40:16.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:50:0)
[07:40:16.526] Timer tick, asking Buildbucket for the build status
[07:40:16.656] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:16.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:51:0) after 1m0s
[07:41:16.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:51:0)
[07:41:16.675] Timer tick, asking Buildbucket for the build status
[07:41:16.713] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:16.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:52:0) after 1m0s
[07:42:16.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:52:0)
[07:42:16.743] Timer tick, asking Buildbucket for the build status
[07:42:16.865] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:16.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:53:0) after 1m0s
[07:43:16.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:53:0)
[07:43:16.888] Timer tick, asking Buildbucket for the build status
[07:43:16.931] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:16.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:54:0) after 1m0s
[07:44:16.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:54:0)
[07:44:16.962] Timer tick, asking Buildbucket for the build status
[07:44:17.193] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:17.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:55:0) after 1m0s
[07:45:17.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:55:0)
[07:45:17.294] Timer tick, asking Buildbucket for the build status
[07:45:17.366] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:17.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:56:0) after 1m0s
[07:46:17.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:56:0)
[07:46:17.385] Timer tick, asking Buildbucket for the build status
[07:46:17.929] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:17.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:57:0) after 1m0s
[07:47:18.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:57:0)
[07:47:18.020] Timer tick, asking Buildbucket for the build status
[07:47:18.139] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:18.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:58:0) after 1m0s
[07:48:18.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:58:0)
[07:48:18.135] Timer tick, asking Buildbucket for the build status
[07:48:18.241] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:18.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:59:0) after 1m0s
[07:49:18.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:59:0)
[07:49:18.259] Timer tick, asking Buildbucket for the build status
[07:49:18.435] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:18.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:60:0) after 1m0s
[07:50:18.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:60:0)
[07:50:18.412] Timer tick, asking Buildbucket for the build status
[07:50:18.578] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:18.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:61:0) after 1m0s
[07:51:18.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:61:0)
[07:51:18.588] Timer tick, asking Buildbucket for the build status
[07:51:18.628] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:18.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:62:0) after 1m0s
[07:52:18.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:62:0)
[07:52:18.675] Timer tick, asking Buildbucket for the build status
[07:52:19.047] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:19.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:63:0) after 1m0s
[07:53:19.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:63:0)
[07:53:19.127] Timer tick, asking Buildbucket for the build status
[07:53:19.253] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:19.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:64:0) after 1m0s
[07:54:19.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:64:0)
[07:54:19.371] Timer tick, asking Buildbucket for the build status
[07:54:19.435] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:19.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:65:0) after 1m0s
[07:55:19.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:65:0)
[07:55:19.464] Timer tick, asking Buildbucket for the build status
[07:55:19.633] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:19.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:66:0) after 1m0s
[07:56:19.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:66:0)
[07:56:19.649] Timer tick, asking Buildbucket for the build status
[07:56:19.742] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:19.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:67:0) after 1m0s
[07:57:19.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:67:0)
[07:57:19.857] Timer tick, asking Buildbucket for the build status
[07:57:20.053] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:20.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:68:0) after 1m0s
[07:58:20.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:68:0)
[07:58:20.075] Timer tick, asking Buildbucket for the build status
[07:58:20.123] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:69:0) after 1m0s
[07:59:20.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:69:0)
[07:59:20.144] Timer tick, asking Buildbucket for the build status
[07:59:20.289] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:70:0) after 1m0s
[08:00:20.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:70:0)
[08:00:20.316] Timer tick, asking Buildbucket for the build status
[08:00:20.473] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:71:0) after 1m0s
[08:01:20.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:71:0)
[08:01:20.511] Timer tick, asking Buildbucket for the build status
[08:01:20.589] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:20.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:72:0) after 1m0s
[08:02:20.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:72:0)
[08:02:20.601] Timer tick, asking Buildbucket for the build status
[08:02:20.760] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:20.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:73:0) after 1m0s
[08:03:20.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:73:0)
[08:03:20.780] Timer tick, asking Buildbucket for the build status
[08:03:21.057] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:21.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:74:0) after 1m0s
[08:04:21.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:74:0)
[08:04:21.042] Timer tick, asking Buildbucket for the build status
[08:04:21.117] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:21.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:75:0) after 1m0s
[08:05:21.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:75:0)
[08:05:21.136] Timer tick, asking Buildbucket for the build status
[08:05:21.221] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:21.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:76:0) after 1m0s
[08:06:21.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:76:0)
[08:06:21.249] Timer tick, asking Buildbucket for the build status
[08:06:21.359] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:21.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:77:0) after 1m0s
[08:07:21.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:77:0)
[08:07:21.389] Timer tick, asking Buildbucket for the build status
[08:07:21.430] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:21.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:78:0) after 1m0s
[08:08:21.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:78:0)
[08:08:21.446] Timer tick, asking Buildbucket for the build status
[08:08:21.489] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:21.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:79:0) after 1m0s
[08:09:21.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:79:0)
[08:09:21.524] Timer tick, asking Buildbucket for the build status
[08:09:21.770] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:21.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:80:0) after 1m0s
[08:10:21.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:80:0)
[08:10:21.791] Timer tick, asking Buildbucket for the build status
[08:10:21.824] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:21.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:81:0) after 1m0s
[08:11:21.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:81:0)
[08:11:21.870] Timer tick, asking Buildbucket for the build status
[08:11:21.923] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:21.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:82:0) after 1m0s
[08:12:21.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:82:0)
[08:12:21.942] Timer tick, asking Buildbucket for the build status
[08:12:22.057] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:22.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:83:0) after 1m0s
[08:13:22.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:83:0)
[08:13:22.080] Timer tick, asking Buildbucket for the build status
[08:13:22.153] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:22.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:84:0) after 1m0s
[08:14:22.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:84:0)
[08:14:22.181] Timer tick, asking Buildbucket for the build status
[08:14:22.485] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:22.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:85:0) after 1m0s
[08:15:22.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:85:0)
[08:15:22.513] Timer tick, asking Buildbucket for the build status
[08:15:22.573] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:22.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:86:0) after 1m0s
[08:16:22.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:86:0)
[08:16:22.594] Timer tick, asking Buildbucket for the build status
[08:16:22.864] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:22.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:87:0) after 1m0s
[08:17:22.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:87:0)
[08:17:22.891] Timer tick, asking Buildbucket for the build status
[08:17:22.933] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:22.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:88:0) after 1m0s
[08:18:22.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:88:0)
[08:18:22.957] Timer tick, asking Buildbucket for the build status
[08:18:23.114] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:23.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:89:0) after 1m0s
[08:19:23.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:89:0)
[08:19:23.156] Timer tick, asking Buildbucket for the build status
[08:19:23.226] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:23.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:90:0) after 1m0s
[08:20:23.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:90:0)
[08:20:23.247] Timer tick, asking Buildbucket for the build status
[08:20:23.503] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:23.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:91:0) after 1m0s
[08:21:23.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:91:0)
[08:21:23.524] Timer tick, asking Buildbucket for the build status
[08:21:23.848] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:23.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:92:0) after 1m0s
[08:22:23.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:92:0)
[08:22:23.847] Timer tick, asking Buildbucket for the build status
[08:22:23.883] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:23.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:93:0) after 1m0s
[08:23:23.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:93:0)
[08:23:23.870] Timer tick, asking Buildbucket for the build status
[08:23:24.014] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:24.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:94:0) after 1m0s
[08:24:24.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:94:0)
[08:24:24.031] Timer tick, asking Buildbucket for the build status
[08:24:24.079] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:24.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:95:0) after 1m0s
[08:25:24.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:95:0)
[08:25:24.110] Timer tick, asking Buildbucket for the build status
[08:25:24.196] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:24.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:96:0) after 1m0s
[08:26:24.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:96:0)
[08:26:24.217] Timer tick, asking Buildbucket for the build status
[08:26:24.395] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:24.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:97:0) after 1m0s
[08:27:24.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:97:0)
[08:27:24.429] Timer tick, asking Buildbucket for the build status
[08:27:24.666] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:24.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:98:0) after 1m0s
[08:28:24.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:98:0)
[08:28:24.713] Timer tick, asking Buildbucket for the build status
[08:28:24.770] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:24.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:99:0) after 1m0s
[08:29:24.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:99:0)
[08:29:24.795] Timer tick, asking Buildbucket for the build status
[08:29:24.950] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:24.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:100:0) after 1m0s
[08:30:24.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:100:0)
[08:30:24.971] Timer tick, asking Buildbucket for the build status
[08:30:25.204] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:25.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:101:0) after 1m0s
[08:31:25.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:101:0)
[08:31:25.255] Timer tick, asking Buildbucket for the build status
[08:31:25.314] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:25.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:102:0) after 1m0s
[08:32:25.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:102:0)
[08:32:25.339] Timer tick, asking Buildbucket for the build status
[08:32:25.516] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:25.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:103:0) after 1m0s
[08:33:25.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:103:0)
[08:33:25.562] Timer tick, asking Buildbucket for the build status
[08:33:25.646] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:25.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:104:0) after 1m0s
[08:34:25.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:104:0)
[08:34:25.675] Timer tick, asking Buildbucket for the build status
[08:34:25.748] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:25.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:105:0) after 1m0s
[08:35:25.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:105:0)
[08:35:25.769] Timer tick, asking Buildbucket for the build status
[08:35:25.852] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:25.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:106:0) after 1m0s
[08:36:25.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:106:0)
[08:36:25.904] Timer tick, asking Buildbucket for the build status
[08:36:26.002] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:26.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:107:0) after 1m0s
[08:37:26.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:107:0)
[08:37:26.053] Timer tick, asking Buildbucket for the build status
[08:37:26.136] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:26.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:108:0) after 1m0s
[08:38:26.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:108:0)
[08:38:26.159] Timer tick, asking Buildbucket for the build status
[08:38:26.260] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:26.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:109:0) after 1m0s
[08:39:26.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:109:0)
[08:39:26.238] Timer tick, asking Buildbucket for the build status
[08:39:26.392] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:26.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:110:0) after 1m0s
[08:40:26.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:110:0)
[08:40:26.411] Timer tick, asking Buildbucket for the build status
[08:40:26.561] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:26.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:111:0) after 1m0s
[08:41:26.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:111:0)
[08:41:26.587] Timer tick, asking Buildbucket for the build status
[08:41:26.654] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:26.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:112:0) after 1m0s
[08:42:26.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:112:0)
[08:42:26.633] Timer tick, asking Buildbucket for the build status
[08:42:26.680] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:26.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:113:0) after 1m0s
[08:43:26.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:113:0)
[08:43:26.703] Timer tick, asking Buildbucket for the build status
[08:43:26.752] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:26.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:114:0) after 1m0s
[08:44:26.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:114:0)
[08:44:26.737] Timer tick, asking Buildbucket for the build status
[08:44:26.862] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:26.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:115:0) after 1m0s
[08:45:26.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:115:0)
[08:45:26.887] Timer tick, asking Buildbucket for the build status
[08:45:27.090] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:27.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:116:0) after 1m0s
[08:46:27.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:116:0)
[08:46:27.118] Timer tick, asking Buildbucket for the build status
[08:46:27.169] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:27.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:117:0) after 1m0s
[08:47:27.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:117:0)
[08:47:27.222] Timer tick, asking Buildbucket for the build status
[08:47:27.287] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:27.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:118:0) after 1m0s
[08:48:27.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:118:0)
[08:48:27.310] Timer tick, asking Buildbucket for the build status
[08:48:27.497] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:27.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:119:0) after 1m0s
[08:49:27.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:119:0)
[08:49:27.524] Timer tick, asking Buildbucket for the build status
[08:49:27.715] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:27.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:120:0) after 1m0s
[08:50:27.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:120:0)
[08:50:27.778] Timer tick, asking Buildbucket for the build status
[08:50:27.927] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:27.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:121:0) after 1m0s
[08:51:27.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:121:0)
[08:51:27.949] Timer tick, asking Buildbucket for the build status
[08:51:28.084] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:28.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:122:0) after 1m0s
[08:52:28.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:122:0)
[08:52:28.083] Timer tick, asking Buildbucket for the build status
[08:52:28.379] Build 8913957472680457344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:28.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079387535077695280:123:0) after 1m0s
[08:52:30.488] Received PubSub notification, asking Buildbucket for the build status
[08:52:30.554] Build 8913957472680457344: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:52:30.554] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1557391950124793",
"created_by": "project:fuchsia",
"created_ts": "1557384722975333",
"failure_reason": "BUILD_FAILURE",
"id": "8913957472680457344",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"366cda03a89c7a03fa0c83003ba4bca42d284230\"}}",
"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-0-75ii\", \"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\": \"366cda03a89c7a03fa0c83003ba4bca42d284230\", \"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-0-75ii\"], \"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\": [\"4291-fed3552\"], \"ssd\": [\"1\"], \"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": "1557384731407444",
"status": "COMPLETED",
"status_changed_ts": "1557384732159006",
"tags": [
"builder:clang",
"buildset:commit/git/366cda03a89c7a03fa0c83003ba4bca42d284230",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/366cda03a89c7a03fa0c83003ba4bca42d284230",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079387535077695280",
"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/8913957472680457344/+/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:44b42e92d4492010",
"user_agent:luci-scheduler"
],
"updated_ts": "1557391950176831",
"url": "https://ci.chromium.org/b/8913957472680457344",
"utcnow_ts": "1557391950548092"
}
}
[08:52:30.554] Invocation finished in 2h0m29.249027046s with status FAILED