[09:53:48.228] New invocation is queued and will start shortly
[09:53:49.377] Starting the invocation (attempt 1)
[09:53:49.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:53:49.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:53:49.420] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081459828592434400",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"7fe7e15b2cf4b5061d339432afcb1f1375a49c27\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081459828592434400",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"gitiles_ref:refs/heads/master"
]
}
[09:53:50.375] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555408429514457",
"id": "8916029766571995008",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7fe7e15b2cf4b5061d339432afcb1f1375a49c27\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555408429891587",
"tags": [
"builder:clang",
"buildset:commit/git/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081459828592434400",
"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/8916029766571995008/+/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": "1555408429891750",
"url": "https://ci.chromium.org/b/8916029766571995008",
"utcnow_ts": "1555408430165403"
}
}
[09:53:50.375] Task URL: https://ci.chromium.org/b/8916029766571995008
[09:53:50.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:2:0) after 1m0s
[09:54:04.998] Received PubSub notification, asking Buildbucket for the build status
[09:54:05.264] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:50.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:2:0)
[09:54:50.378] Timer tick, asking Buildbucket for the build status
[09:54:50.468] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:50.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:4:0) after 1m0s
[09:55:50.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:4:0)
[09:55:50.460] Timer tick, asking Buildbucket for the build status
[09:55:50.761] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:50.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:5:0) after 1m0s
[09:56:50.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:5:0)
[09:56:50.777] Timer tick, asking Buildbucket for the build status
[09:56:50.842] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:50.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:6:0) after 1m0s
[09:57:50.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:6:0)
[09:57:50.860] Timer tick, asking Buildbucket for the build status
[09:57:50.903] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:50.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:7:0) after 1m0s
[09:58:50.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:7:0)
[09:58:50.919] Timer tick, asking Buildbucket for the build status
[09:58:50.988] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:50.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:8:0) after 1m0s
[09:59:51.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:8:0)
[09:59:51.009] Timer tick, asking Buildbucket for the build status
[09:59:51.111] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:51.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:9:0) after 1m0s
[10:00:51.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:9:0)
[10:00:51.131] Timer tick, asking Buildbucket for the build status
[10:00:51.198] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:51.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:10:0) after 1m0s
[10:01:51.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:10:0)
[10:01:51.218] Timer tick, asking Buildbucket for the build status
[10:01:51.393] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:51.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:11:0) after 1m0s
[10:02:51.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:11:0)
[10:02:51.418] Timer tick, asking Buildbucket for the build status
[10:02:51.457] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:51.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:12:0) after 1m0s
[10:03:51.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:12:0)
[10:03:51.540] Timer tick, asking Buildbucket for the build status
[10:03:51.636] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:51.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:13:0) after 1m0s
[10:04:51.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:13:0)
[10:04:51.655] Timer tick, asking Buildbucket for the build status
[10:04:51.736] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:51.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:14:0) after 1m0s
[10:05:51.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:14:0)
[10:05:51.722] Timer tick, asking Buildbucket for the build status
[10:05:51.762] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:51.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:15:0) after 1m0s
[10:06:51.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:15:0)
[10:06:51.782] Timer tick, asking Buildbucket for the build status
[10:06:51.918] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:51.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:16:0) after 1m0s
[10:07:51.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:16:0)
[10:07:51.900] Timer tick, asking Buildbucket for the build status
[10:07:51.992] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:51.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:17:0) after 1m0s
[10:08:52.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:17:0)
[10:08:52.009] Timer tick, asking Buildbucket for the build status
[10:08:52.187] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:52.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:18:0) after 1m0s
[10:09:52.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:18:0)
[10:09:52.204] Timer tick, asking Buildbucket for the build status
[10:09:52.285] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:52.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:19:0) after 1m0s
[10:10:52.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:19:0)
[10:10:52.307] Timer tick, asking Buildbucket for the build status
[10:10:52.372] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:52.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:20:0) after 1m0s
[10:11:52.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:20:0)
[10:11:52.393] Timer tick, asking Buildbucket for the build status
[10:11:52.536] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:52.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:21:0) after 1m0s
[10:12:52.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:21:0)
[10:12:52.556] Timer tick, asking Buildbucket for the build status
[10:12:52.975] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:52.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:22:0) after 1m0s
[10:13:52.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:22:0)
[10:13:52.993] Timer tick, asking Buildbucket for the build status
[10:13:53.103] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:53.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:23:0) after 1m0s
[10:14:53.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:23:0)
[10:14:53.122] Timer tick, asking Buildbucket for the build status
[10:14:53.177] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:53.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:24:0) after 1m0s
[10:15:53.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:24:0)
[10:15:53.164] Timer tick, asking Buildbucket for the build status
[10:15:53.218] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:53.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:25:0) after 1m0s
[10:16:53.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:25:0)
[10:16:53.206] Timer tick, asking Buildbucket for the build status
[10:16:53.325] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:53.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:26:0) after 1m0s
[10:17:53.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:26:0)
[10:17:53.305] Timer tick, asking Buildbucket for the build status
[10:17:53.368] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:53.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:27:0) after 1m0s
[10:18:53.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:27:0)
[10:18:53.350] Timer tick, asking Buildbucket for the build status
[10:18:53.436] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:53.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:28:0) after 1m0s
[10:19:53.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:28:0)
[10:19:53.454] Timer tick, asking Buildbucket for the build status
[10:19:53.536] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:53.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:29:0) after 1m0s
[10:20:53.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:29:0)
[10:20:53.554] Timer tick, asking Buildbucket for the build status
[10:20:53.603] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:53.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:30:0) after 1m0s
[10:21:53.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:30:0)
[10:21:53.623] Timer tick, asking Buildbucket for the build status
[10:21:53.689] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:53.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:31:0) after 1m0s
[10:22:53.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:31:0)
[10:22:53.705] Timer tick, asking Buildbucket for the build status
[10:22:53.880] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:53.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:32:0) after 1m0s
[10:23:53.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:32:0)
[10:23:53.919] Timer tick, asking Buildbucket for the build status
[10:23:54.004] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:54.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:33:0) after 1m0s
[10:24:54.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:33:0)
[10:24:54.028] Timer tick, asking Buildbucket for the build status
[10:24:54.246] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:54.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:34:0) after 1m0s
[10:25:54.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:34:0)
[10:25:54.264] Timer tick, asking Buildbucket for the build status
[10:25:54.663] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:54.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:35:0) after 1m0s
[10:26:54.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:35:0)
[10:26:54.686] Timer tick, asking Buildbucket for the build status
[10:26:54.919] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:54.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:36:0) after 1m0s
[10:27:54.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:36:0)
[10:27:54.917] Timer tick, asking Buildbucket for the build status
[10:27:55.103] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:55.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:37:0) after 1m0s
[10:28:55.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:37:0)
[10:28:55.094] Timer tick, asking Buildbucket for the build status
[10:28:55.132] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:55.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:38:0) after 1m0s
[10:29:55.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:38:0)
[10:29:55.149] Timer tick, asking Buildbucket for the build status
[10:29:55.219] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:55.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:39:0) after 1m0s
[10:30:55.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:39:0)
[10:30:55.239] Timer tick, asking Buildbucket for the build status
[10:30:55.393] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:55.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:40:0) after 1m0s
[10:31:55.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:40:0)
[10:31:55.414] Timer tick, asking Buildbucket for the build status
[10:31:55.594] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:55.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:41:0) after 1m0s
[10:32:55.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:41:0)
[10:32:55.626] Timer tick, asking Buildbucket for the build status
[10:32:55.697] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:55.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:42:0) after 1m0s
[10:33:55.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:42:0)
[10:33:55.722] Timer tick, asking Buildbucket for the build status
[10:33:55.785] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:55.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:43:0) after 1m0s
[10:34:55.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:43:0)
[10:34:55.824] Timer tick, asking Buildbucket for the build status
[10:34:55.890] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:55.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:44:0) after 1m0s
[10:35:55.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:44:0)
[10:35:55.907] Timer tick, asking Buildbucket for the build status
[10:35:55.957] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:55.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:45:0) after 1m0s
[10:36:55.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:45:0)
[10:36:55.973] Timer tick, asking Buildbucket for the build status
[10:36:56.081] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:56.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:46:0) after 1m0s
[10:37:56.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:46:0)
[10:37:56.101] Timer tick, asking Buildbucket for the build status
[10:37:56.283] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:56.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:47:0) after 1m0s
[10:38:56.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:47:0)
[10:38:56.301] Timer tick, asking Buildbucket for the build status
[10:38:56.462] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:56.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:48:0) after 1m0s
[10:39:56.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:48:0)
[10:39:56.481] Timer tick, asking Buildbucket for the build status
[10:39:56.540] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:56.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:49:0) after 1m0s
[10:40:56.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:49:0)
[10:40:56.557] Timer tick, asking Buildbucket for the build status
[10:40:56.598] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:56.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:50:0) after 1m0s
[10:41:56.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:50:0)
[10:41:56.615] Timer tick, asking Buildbucket for the build status
[10:41:56.676] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:56.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:51:0) after 1m0s
[10:42:56.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:51:0)
[10:42:56.697] Timer tick, asking Buildbucket for the build status
[10:42:56.782] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:56.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:52:0) after 1m0s
[10:43:56.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:52:0)
[10:43:56.783] Timer tick, asking Buildbucket for the build status
[10:43:56.824] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:56.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:53:0) after 1m0s
[10:44:56.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:53:0)
[10:44:56.807] Timer tick, asking Buildbucket for the build status
[10:44:56.848] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:56.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:54:0) after 1m0s
[10:45:56.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:54:0)
[10:45:56.865] Timer tick, asking Buildbucket for the build status
[10:45:57.080] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:57.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:55:0) after 1m0s
[10:46:57.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:55:0)
[10:46:57.056] Timer tick, asking Buildbucket for the build status
[10:46:57.116] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:57.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:56:0) after 1m0s
[10:47:57.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:56:0)
[10:47:57.111] Timer tick, asking Buildbucket for the build status
[10:47:57.169] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:57.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:57:0) after 1m0s
[10:48:57.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:57:0)
[10:48:57.190] Timer tick, asking Buildbucket for the build status
[10:48:57.291] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:57.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:58:0) after 1m0s
[10:49:57.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:58:0)
[10:49:57.308] Timer tick, asking Buildbucket for the build status
[10:49:57.362] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:57.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:59:0) after 1m0s
[10:50:57.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:59:0)
[10:50:57.380] Timer tick, asking Buildbucket for the build status
[10:50:57.446] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:57.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:60:0) after 1m0s
[10:51:57.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:60:0)
[10:51:57.441] Timer tick, asking Buildbucket for the build status
[10:51:57.579] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:57.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:61:0) after 1m0s
[10:52:57.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:61:0)
[10:52:57.601] Timer tick, asking Buildbucket for the build status
[10:52:57.750] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:57.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:62:0) after 1m0s
[10:53:57.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:62:0)
[10:53:57.770] Timer tick, asking Buildbucket for the build status
[10:53:57.817] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:57.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:63:0) after 1m0s
[10:54:57.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:63:0)
[10:54:57.841] Timer tick, asking Buildbucket for the build status
[10:54:59.163] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:59.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:64:0) after 1m0s
[10:55:59.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:64:0)
[10:55:59.151] Timer tick, asking Buildbucket for the build status
[10:55:59.214] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:59.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:65:0) after 1m0s
[10:56:59.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:65:0)
[10:56:59.209] Timer tick, asking Buildbucket for the build status
[10:56:59.342] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:59.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:66:0) after 1m0s
[10:57:59.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:66:0)
[10:57:59.362] Timer tick, asking Buildbucket for the build status
[10:57:59.654] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:59.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:67:0) after 1m0s
[10:58:59.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:67:0)
[10:58:59.674] Timer tick, asking Buildbucket for the build status
[10:58:59.797] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:59.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:68:0) after 1m0s
[10:59:59.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:68:0)
[10:59:59.837] Timer tick, asking Buildbucket for the build status
[10:59:59.993] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:59.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:69:0) after 1m0s
[11:01:00.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:69:0)
[11:01:00.011] Timer tick, asking Buildbucket for the build status
[11:01:00.102] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:00.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:70:0) after 1m0s
[11:02:00.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:70:0)
[11:02:00.172] Timer tick, asking Buildbucket for the build status
[11:02:00.245] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:00.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:71:0) after 1m0s
[11:03:00.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:71:0)
[11:03:00.282] Timer tick, asking Buildbucket for the build status
[11:03:00.373] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:00.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:72:0) after 1m0s
[11:04:00.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:72:0)
[11:04:00.395] Timer tick, asking Buildbucket for the build status
[11:04:00.534] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:00.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:73:0) after 1m0s
[11:05:00.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:73:0)
[11:05:00.556] Timer tick, asking Buildbucket for the build status
[11:05:00.648] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:00.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:74:0) after 1m0s
[11:06:00.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:74:0)
[11:06:00.668] Timer tick, asking Buildbucket for the build status
[11:06:00.742] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:00.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:75:0) after 1m0s
[11:07:00.746] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:75:0)
[11:07:00.746] Timer tick, asking Buildbucket for the build status
[11:07:00.802] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:00.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:76:0) after 1m0s
[11:08:00.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:76:0)
[11:08:00.788] Timer tick, asking Buildbucket for the build status
[11:08:00.854] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:00.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:77:0) after 1m0s
[11:09:00.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:77:0)
[11:09:00.877] Timer tick, asking Buildbucket for the build status
[11:09:00.928] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:00.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:78:0) after 1m0s
[11:10:00.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:78:0)
[11:10:00.948] Timer tick, asking Buildbucket for the build status
[11:10:01.134] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:01.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:79:0) after 1m0s
[11:11:01.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:79:0)
[11:11:01.181] Timer tick, asking Buildbucket for the build status
[11:11:01.446] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:01.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:80:0) after 1m0s
[11:12:01.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:80:0)
[11:12:01.480] Timer tick, asking Buildbucket for the build status
[11:12:01.579] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:01.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:81:0) after 1m0s
[11:13:01.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:81:0)
[11:13:01.617] Timer tick, asking Buildbucket for the build status
[11:13:01.815] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:01.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:82:0) after 1m0s
[11:14:01.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:82:0)
[11:14:01.835] Timer tick, asking Buildbucket for the build status
[11:14:01.884] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:01.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:83:0) after 1m0s
[11:15:01.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:83:0)
[11:15:01.907] Timer tick, asking Buildbucket for the build status
[11:15:02.074] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:02.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:84:0) after 1m0s
[11:16:02.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:84:0)
[11:16:02.094] Timer tick, asking Buildbucket for the build status
[11:16:02.269] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:02.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:85:0) after 1m0s
[11:17:02.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:85:0)
[11:17:02.275] Timer tick, asking Buildbucket for the build status
[11:17:02.401] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:02.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:86:0) after 1m0s
[11:18:02.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:86:0)
[11:18:02.414] Timer tick, asking Buildbucket for the build status
[11:18:05.001] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:05.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:87:0) after 1m0s
[11:19:04.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:87:0)
[11:19:04.996] Timer tick, asking Buildbucket for the build status
[11:19:05.077] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:05.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:88:0) after 1m0s
[11:20:05.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:88:0)
[11:20:05.101] Timer tick, asking Buildbucket for the build status
[11:20:05.169] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:05.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:89:0) after 1m0s
[11:21:05.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:89:0)
[11:21:05.174] Timer tick, asking Buildbucket for the build status
[11:21:05.225] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:05.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:90:0) after 1m0s
[11:22:05.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:90:0)
[11:22:05.246] Timer tick, asking Buildbucket for the build status
[11:22:05.299] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:05.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:91:0) after 1m0s
[11:23:05.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:91:0)
[11:23:05.287] Timer tick, asking Buildbucket for the build status
[11:23:05.327] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:05.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:92:0) after 1m0s
[11:24:05.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:92:0)
[11:24:05.352] Timer tick, asking Buildbucket for the build status
[11:24:05.517] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:05.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:93:0) after 1m0s
[11:25:05.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:93:0)
[11:25:05.504] Timer tick, asking Buildbucket for the build status
[11:25:05.568] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:05.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:94:0) after 1m0s
[11:26:05.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:94:0)
[11:26:05.590] Timer tick, asking Buildbucket for the build status
[11:26:05.638] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:05.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:95:0) after 1m0s
[11:27:05.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:95:0)
[11:27:05.666] Timer tick, asking Buildbucket for the build status
[11:27:05.750] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:05.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:96:0) after 1m0s
[11:28:05.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:96:0)
[11:28:05.804] Timer tick, asking Buildbucket for the build status
[11:28:05.916] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:05.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:97:0) after 1m0s
[11:29:05.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:97:0)
[11:29:05.895] Timer tick, asking Buildbucket for the build status
[11:29:05.950] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:05.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:98:0) after 1m0s
[11:30:06.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:98:0)
[11:30:06.001] Timer tick, asking Buildbucket for the build status
[11:30:06.066] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:06.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:99:0) after 1m0s
[11:31:06.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:99:0)
[11:31:06.172] Timer tick, asking Buildbucket for the build status
[11:31:06.223] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:06.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:100:0) after 1m0s
[11:32:06.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:100:0)
[11:32:06.247] Timer tick, asking Buildbucket for the build status
[11:32:06.480] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:06.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:101:0) after 1m0s
[11:33:06.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:101:0)
[11:33:06.501] Timer tick, asking Buildbucket for the build status
[11:33:06.545] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:06.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:102:0) after 1m0s
[11:34:06.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:102:0)
[11:34:06.565] Timer tick, asking Buildbucket for the build status
[11:34:06.624] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:06.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:103:0) after 1m0s
[11:35:06.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:103:0)
[11:35:06.636] Timer tick, asking Buildbucket for the build status
[11:35:06.677] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:06.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:104:0) after 1m0s
[11:36:06.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:104:0)
[11:36:06.700] Timer tick, asking Buildbucket for the build status
[11:36:06.766] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:06.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:105:0) after 1m0s
[11:37:06.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:105:0)
[11:37:06.786] Timer tick, asking Buildbucket for the build status
[11:37:06.824] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:06.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:106:0) after 1m0s
[11:38:06.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:106:0)
[11:38:06.847] Timer tick, asking Buildbucket for the build status
[11:38:06.914] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:06.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:107:0) after 1m0s
[11:39:06.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:107:0)
[11:39:06.899] Timer tick, asking Buildbucket for the build status
[11:39:06.966] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:06.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:108:0) after 1m0s
[11:40:06.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:108:0)
[11:40:06.988] Timer tick, asking Buildbucket for the build status
[11:40:07.184] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:07.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:109:0) after 1m0s
[11:41:07.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:109:0)
[11:41:07.215] Timer tick, asking Buildbucket for the build status
[11:41:07.369] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:07.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:110:0) after 1m0s
[11:42:07.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:110:0)
[11:42:07.387] Timer tick, asking Buildbucket for the build status
[11:42:07.454] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:07.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:111:0) after 1m0s
[11:43:07.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:111:0)
[11:43:07.510] Timer tick, asking Buildbucket for the build status
[11:43:07.735] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:07.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:112:0) after 1m0s
[11:44:07.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:112:0)
[11:44:07.757] Timer tick, asking Buildbucket for the build status
[11:44:07.894] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:07.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:113:0) after 1m0s
[11:45:07.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:113:0)
[11:45:07.919] Timer tick, asking Buildbucket for the build status
[11:45:07.988] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:07.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:114:0) after 1m0s
[11:46:08.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:114:0)
[11:46:08.012] Timer tick, asking Buildbucket for the build status
[11:46:08.167] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:08.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:115:0) after 1m0s
[11:47:08.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:115:0)
[11:47:08.188] Timer tick, asking Buildbucket for the build status
[11:47:08.499] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:08.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:116:0) after 1m0s
[11:48:08.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:116:0)
[11:48:08.551] Timer tick, asking Buildbucket for the build status
[11:48:08.838] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:08.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:117:0) after 1m0s
[11:49:08.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:117:0)
[11:49:08.862] Timer tick, asking Buildbucket for the build status
[11:49:09.026] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:09.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:118:0) after 1m0s
[11:50:09.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:118:0)
[11:50:09.001] Timer tick, asking Buildbucket for the build status
[11:50:09.091] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:09.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:119:0) after 1m0s
[11:51:09.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:119:0)
[11:51:09.114] Timer tick, asking Buildbucket for the build status
[11:51:09.200] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:09.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:120:0) after 1m0s
[11:52:09.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:120:0)
[11:52:09.185] Timer tick, asking Buildbucket for the build status
[11:52:09.276] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:09.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:121:0) after 1m0s
[11:53:09.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:121:0)
[11:53:09.297] Timer tick, asking Buildbucket for the build status
[11:53:09.363] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:09.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:122:0) after 1m0s
[11:54:09.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:122:0)
[11:54:09.346] Timer tick, asking Buildbucket for the build status
[11:54:09.416] Build 8916029766571995008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:09.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081459828592434400:123:0) after 1m0s
[11:54:26.710] Received PubSub notification, asking Buildbucket for the build status
[11:54:26.749] Build 8916029766571995008: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:54:26.749] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555415666391495",
"created_by": "project:fuchsia",
"created_ts": "1555408429514457",
"failure_reason": "BUILD_FAILURE",
"id": "8916029766571995008",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7fe7e15b2cf4b5061d339432afcb1f1375a49c27\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-7z8w\", \"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\": \"7fe7e15b2cf4b5061d339432afcb1f1375a49c27\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-7z8w\"], \"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\": [\"4256-d1e1f70\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555408444240920",
"status": "COMPLETED",
"status_changed_ts": "1555408444688182",
"tags": [
"builder:clang",
"buildset:commit/git/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7fe7e15b2cf4b5061d339432afcb1f1375a49c27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081459828592434400",
"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/8916029766571995008/+/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:443e62ad4549ca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555415666439553",
"url": "https://ci.chromium.org/b/8916029766571995008",
"utcnow_ts": "1555415666740245"
}
}
[11:54:26.749] Invocation finished in 2h0m38.545251009s with status FAILED