[16:18:44.213] New invocation is queued and will start shortly
[16:18:45.307] Starting the invocation (attempt 1)
[16:18:45.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:18:45.392] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:18:45.392] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083609937918292336",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"2e92846d365a95b1379ed12c351582b681ce3d99\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083609937918292336",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/2e92846d365a95b1379ed12c351582b681ce3d99",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2e92846d365a95b1379ed12c351582b681ce3d99",
"gitiles_ref:refs/heads/master"
]
}
[16:18:46.533] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553357925498318",
"id": "8918179875871726928",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2e92846d365a95b1379ed12c351582b681ce3d99\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553357926398363",
"tags": [
"builder:clang",
"buildset:commit/git/2e92846d365a95b1379ed12c351582b681ce3d99",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2e92846d365a95b1379ed12c351582b681ce3d99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083609937918292336",
"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/8918179875871726928/+/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:43c42a7669ff4210",
"user_agent:luci-scheduler"
],
"updated_ts": "1553357926398500",
"url": "https://ci.chromium.org/b/8918179875871726928",
"utcnow_ts": "1553357926519653"
}
}
[16:18:46.533] Task URL: https://ci.chromium.org/b/8918179875871726928
[16:18:46.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:2:0) after 1m0s
[16:19:07.582] Received PubSub notification, asking Buildbucket for the build status
[16:19:07.698] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:46.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:2:0)
[16:19:46.504] Timer tick, asking Buildbucket for the build status
[16:19:46.631] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:46.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:4:0) after 1m0s
[16:20:46.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:4:0)
[16:20:46.608] Timer tick, asking Buildbucket for the build status
[16:20:46.754] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:46.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:5:0) after 1m0s
[16:21:46.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:5:0)
[16:21:46.724] Timer tick, asking Buildbucket for the build status
[16:21:46.781] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:46.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:6:0) after 1m0s
[16:22:46.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:6:0)
[16:22:46.762] Timer tick, asking Buildbucket for the build status
[16:22:46.833] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:46.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:7:0) after 1m0s
[16:23:46.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:7:0)
[16:23:46.854] Timer tick, asking Buildbucket for the build status
[16:23:46.916] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:46.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:8:0) after 1m0s
[16:24:46.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:8:0)
[16:24:46.933] Timer tick, asking Buildbucket for the build status
[16:24:47.158] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:47.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:9:0) after 1m0s
[16:25:47.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:9:0)
[16:25:47.146] Timer tick, asking Buildbucket for the build status
[16:25:47.271] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:47.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:10:0) after 1m0s
[16:26:47.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:10:0)
[16:26:47.272] Timer tick, asking Buildbucket for the build status
[16:26:47.337] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:47.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:11:0) after 1m0s
[16:27:47.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:11:0)
[16:27:47.306] Timer tick, asking Buildbucket for the build status
[16:27:47.366] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:47.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:12:0) after 1m0s
[16:28:47.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:12:0)
[16:28:47.377] Timer tick, asking Buildbucket for the build status
[16:28:47.560] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:47.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:13:0) after 1m0s
[16:29:47.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:13:0)
[16:29:47.564] Timer tick, asking Buildbucket for the build status
[16:29:47.698] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:47.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:14:0) after 1m0s
[16:30:47.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:14:0)
[16:30:47.715] Timer tick, asking Buildbucket for the build status
[16:30:47.784] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:47.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:15:0) after 1m0s
[16:31:47.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:15:0)
[16:31:47.801] Timer tick, asking Buildbucket for the build status
[16:31:47.867] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:47.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:16:0) after 1m0s
[16:32:47.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:16:0)
[16:32:47.899] Timer tick, asking Buildbucket for the build status
[16:32:47.987] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:47.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:17:0) after 1m0s
[16:33:48.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:17:0)
[16:33:48.008] Timer tick, asking Buildbucket for the build status
[16:33:48.099] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:48.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:18:0) after 1m0s
[16:34:48.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:18:0)
[16:34:48.148] Timer tick, asking Buildbucket for the build status
[16:34:48.214] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:48.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:19:0) after 1m0s
[16:35:48.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:19:0)
[16:35:48.232] Timer tick, asking Buildbucket for the build status
[16:35:48.288] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:20:0) after 1m0s
[16:36:48.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:20:0)
[16:36:48.275] Timer tick, asking Buildbucket for the build status
[16:36:48.367] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:21:0) after 1m0s
[16:37:48.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:21:0)
[16:37:48.346] Timer tick, asking Buildbucket for the build status
[16:37:48.474] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:48.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:22:0) after 1m0s
[16:38:48.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:22:0)
[16:38:48.455] Timer tick, asking Buildbucket for the build status
[16:38:48.540] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:48.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:23:0) after 1m0s
[16:39:48.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:23:0)
[16:39:48.535] Timer tick, asking Buildbucket for the build status
[16:39:48.791] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:48.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:24:0) after 1m0s
[16:40:48.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:24:0)
[16:40:48.801] Timer tick, asking Buildbucket for the build status
[16:40:48.909] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:48.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:25:0) after 1m0s
[16:41:48.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:25:0)
[16:41:48.884] Timer tick, asking Buildbucket for the build status
[16:41:49.212] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:49.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:26:0) after 1m0s
[16:42:49.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:26:0)
[16:42:49.296] Timer tick, asking Buildbucket for the build status
[16:42:49.473] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:49.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:27:0) after 1m0s
[16:43:49.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:27:0)
[16:43:49.479] Timer tick, asking Buildbucket for the build status
[16:43:49.535] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:49.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:28:0) after 1m0s
[16:44:49.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:28:0)
[16:44:49.553] Timer tick, asking Buildbucket for the build status
[16:44:49.672] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:49.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:29:0) after 1m0s
[16:45:49.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:29:0)
[16:45:49.740] Timer tick, asking Buildbucket for the build status
[16:45:50.355] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:50.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:30:0) after 1m0s
[16:46:50.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:30:0)
[16:46:50.333] Timer tick, asking Buildbucket for the build status
[16:46:50.536] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:50.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:31:0) after 1m0s
[16:47:50.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:31:0)
[16:47:50.564] Timer tick, asking Buildbucket for the build status
[16:47:50.624] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:50.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:32:0) after 1m0s
[16:48:50.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:32:0)
[16:48:50.627] Timer tick, asking Buildbucket for the build status
[16:48:50.798] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:50.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:33:0) after 1m0s
[16:49:50.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:33:0)
[16:49:50.831] Timer tick, asking Buildbucket for the build status
[16:49:50.896] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:50.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:34:0) after 1m0s
[16:50:50.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:34:0)
[16:50:50.926] Timer tick, asking Buildbucket for the build status
[16:50:51.008] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:51.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:35:0) after 1m0s
[16:51:51.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:35:0)
[16:51:51.028] Timer tick, asking Buildbucket for the build status
[16:51:51.184] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:51.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:36:0) after 1m0s
[16:52:51.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:36:0)
[16:52:51.232] Timer tick, asking Buildbucket for the build status
[16:52:51.357] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:51.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:37:0) after 1m0s
[16:53:51.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:37:0)
[16:53:51.421] Timer tick, asking Buildbucket for the build status
[16:53:51.478] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:51.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:38:0) after 1m0s
[16:54:51.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:38:0)
[16:54:51.502] Timer tick, asking Buildbucket for the build status
[16:54:52.309] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:52.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:39:0) after 1m0s
[16:55:52.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:39:0)
[16:55:52.345] Timer tick, asking Buildbucket for the build status
[16:55:52.548] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:52.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:40:0) after 1m0s
[16:56:52.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:40:0)
[16:56:52.551] Timer tick, asking Buildbucket for the build status
[16:56:52.672] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:52.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:41:0) after 1m0s
[16:57:52.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:41:0)
[16:57:52.670] Timer tick, asking Buildbucket for the build status
[16:57:52.829] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:52.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:42:0) after 1m0s
[16:58:52.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:42:0)
[16:58:52.817] Timer tick, asking Buildbucket for the build status
[16:58:52.978] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:52.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:43:0) after 1m0s
[16:59:52.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:43:0)
[16:59:52.960] Timer tick, asking Buildbucket for the build status
[16:59:53.127] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:53.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:44:0) after 1m0s
[17:00:53.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:44:0)
[17:00:53.149] Timer tick, asking Buildbucket for the build status
[17:00:53.205] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:53.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:45:0) after 1m0s
[17:01:53.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:45:0)
[17:01:53.210] Timer tick, asking Buildbucket for the build status
[17:01:53.352] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:53.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:46:0) after 1m0s
[17:02:53.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:46:0)
[17:02:53.342] Timer tick, asking Buildbucket for the build status
[17:02:53.399] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:53.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:47:0) after 1m0s
[17:03:53.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:47:0)
[17:03:53.421] Timer tick, asking Buildbucket for the build status
[17:03:53.701] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:53.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:48:0) after 1m0s
[17:04:53.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:48:0)
[17:04:53.711] Timer tick, asking Buildbucket for the build status
[17:04:53.814] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:53.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:49:0) after 1m0s
[17:05:53.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:49:0)
[17:05:53.880] Timer tick, asking Buildbucket for the build status
[17:05:53.957] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:53.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:50:0) after 1m0s
[17:06:53.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:50:0)
[17:06:53.949] Timer tick, asking Buildbucket for the build status
[17:06:54.022] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:54.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:51:0) after 1m0s
[17:07:53.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:51:0)
[17:07:53.995] Timer tick, asking Buildbucket for the build status
[17:07:54.214] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:54.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:52:0) after 1m0s
[17:08:54.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:52:0)
[17:08:54.246] Timer tick, asking Buildbucket for the build status
[17:08:54.325] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:54.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:53:0) after 1m0s
[17:09:54.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:53:0)
[17:09:54.362] Timer tick, asking Buildbucket for the build status
[17:09:54.412] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:54.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:54:0) after 1m0s
[17:10:54.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:54:0)
[17:10:54.431] Timer tick, asking Buildbucket for the build status
[17:10:54.634] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:54.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:55:0) after 1m0s
[17:11:54.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:55:0)
[17:11:54.666] Timer tick, asking Buildbucket for the build status
[17:11:54.742] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:54.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:56:0) after 1m0s
[17:12:54.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:56:0)
[17:12:54.766] Timer tick, asking Buildbucket for the build status
[17:12:54.843] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:54.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:57:0) after 1m0s
[17:13:54.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:57:0)
[17:13:54.950] Timer tick, asking Buildbucket for the build status
[17:13:55.132] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:55.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:58:0) after 1m0s
[17:14:55.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:58:0)
[17:14:55.109] Timer tick, asking Buildbucket for the build status
[17:14:55.168] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:55.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:59:0) after 1m0s
[17:15:55.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:59:0)
[17:15:55.188] Timer tick, asking Buildbucket for the build status
[17:15:55.248] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:55.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:60:0) after 1m0s
[17:16:55.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:60:0)
[17:16:55.242] Timer tick, asking Buildbucket for the build status
[17:16:55.315] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:61:0) after 1m0s
[17:17:55.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:61:0)
[17:17:55.315] Timer tick, asking Buildbucket for the build status
[17:17:55.404] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:55.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:62:0) after 1m0s
[17:18:55.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:62:0)
[17:18:55.426] Timer tick, asking Buildbucket for the build status
[17:18:55.510] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:55.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:63:0) after 1m0s
[17:19:55.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:63:0)
[17:19:55.485] Timer tick, asking Buildbucket for the build status
[17:19:55.603] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:55.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:64:0) after 1m0s
[17:20:55.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:64:0)
[17:20:55.662] Timer tick, asking Buildbucket for the build status
[17:20:55.780] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:55.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:65:0) after 1m0s
[17:21:55.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:65:0)
[17:21:55.877] Timer tick, asking Buildbucket for the build status
[17:21:56.068] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:56.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:66:0) after 1m0s
[17:22:56.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:66:0)
[17:22:56.078] Timer tick, asking Buildbucket for the build status
[17:22:56.203] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:56.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:67:0) after 1m0s
[17:23:56.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:67:0)
[17:23:56.225] Timer tick, asking Buildbucket for the build status
[17:23:56.593] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:56.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:68:0) after 1m0s
[17:24:56.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:68:0)
[17:24:56.618] Timer tick, asking Buildbucket for the build status
[17:24:56.760] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:56.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:69:0) after 1m0s
[17:25:56.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:69:0)
[17:25:56.812] Timer tick, asking Buildbucket for the build status
[17:25:56.903] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:56.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:70:0) after 1m0s
[17:26:56.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:70:0)
[17:26:56.902] Timer tick, asking Buildbucket for the build status
[17:26:56.967] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:56.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:71:0) after 1m0s
[17:27:57.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:71:0)
[17:27:57.057] Timer tick, asking Buildbucket for the build status
[17:27:57.300] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:57.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:72:0) after 1m0s
[17:28:57.322] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:72:0)
[17:28:57.323] Timer tick, asking Buildbucket for the build status
[17:28:57.385] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:57.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:73:0) after 1m0s
[17:29:57.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:73:0)
[17:29:57.427] Timer tick, asking Buildbucket for the build status
[17:29:57.530] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:57.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:74:0) after 1m0s
[17:30:57.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:74:0)
[17:30:57.552] Timer tick, asking Buildbucket for the build status
[17:30:57.756] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:57.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:75:0) after 1m0s
[17:31:57.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:75:0)
[17:31:57.777] Timer tick, asking Buildbucket for the build status
[17:31:57.840] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:57.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:76:0) after 1m0s
[17:32:57.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:76:0)
[17:32:57.861] Timer tick, asking Buildbucket for the build status
[17:32:58.078] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:58.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:77:0) after 1m0s
[17:33:58.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:77:0)
[17:33:58.131] Timer tick, asking Buildbucket for the build status
[17:33:58.229] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:58.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:78:0) after 1m0s
[17:34:58.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:78:0)
[17:34:58.203] Timer tick, asking Buildbucket for the build status
[17:34:58.400] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:58.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:79:0) after 1m0s
[17:35:58.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:79:0)
[17:35:58.391] Timer tick, asking Buildbucket for the build status
[17:35:58.580] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:58.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:80:0) after 1m0s
[17:36:58.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:80:0)
[17:36:58.607] Timer tick, asking Buildbucket for the build status
[17:36:59.082] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:59.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:81:0) after 1m0s
[17:37:59.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:81:0)
[17:37:59.066] Timer tick, asking Buildbucket for the build status
[17:37:59.268] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:59.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:82:0) after 1m0s
[17:38:59.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:82:0)
[17:38:59.298] Timer tick, asking Buildbucket for the build status
[17:38:59.351] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:59.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:83:0) after 1m0s
[17:39:59.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:83:0)
[17:39:59.374] Timer tick, asking Buildbucket for the build status
[17:39:59.470] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:59.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:84:0) after 1m0s
[17:40:59.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:84:0)
[17:40:59.491] Timer tick, asking Buildbucket for the build status
[17:40:59.550] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:59.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:85:0) after 1m0s
[17:41:59.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:85:0)
[17:41:59.582] Timer tick, asking Buildbucket for the build status
[17:41:59.714] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:59.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:86:0) after 1m0s
[17:42:59.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:86:0)
[17:42:59.735] Timer tick, asking Buildbucket for the build status
[17:42:59.898] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:59.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:87:0) after 1m0s
[17:43:59.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:87:0)
[17:43:59.877] Timer tick, asking Buildbucket for the build status
[17:43:59.985] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:59.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:88:0) after 1m0s
[17:44:59.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:88:0)
[17:44:59.959] Timer tick, asking Buildbucket for the build status
[17:45:00.018] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:00.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:89:0) after 1m0s
[17:46:00.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:89:0)
[17:46:00.046] Timer tick, asking Buildbucket for the build status
[17:46:00.208] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:00.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:90:0) after 1m0s
[17:47:00.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:90:0)
[17:47:00.210] Timer tick, asking Buildbucket for the build status
[17:47:00.276] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:00.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:91:0) after 1m0s
[17:48:00.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:91:0)
[17:48:00.282] Timer tick, asking Buildbucket for the build status
[17:48:00.349] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:00.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:92:0) after 1m0s
[17:49:00.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:92:0)
[17:49:00.389] Timer tick, asking Buildbucket for the build status
[17:49:00.499] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:00.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:93:0) after 1m0s
[17:50:00.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:93:0)
[17:50:00.499] Timer tick, asking Buildbucket for the build status
[17:50:00.828] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:00.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:94:0) after 1m0s
[17:51:00.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:94:0)
[17:51:00.848] Timer tick, asking Buildbucket for the build status
[17:51:00.974] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:00.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:95:0) after 1m0s
[17:52:00.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:95:0)
[17:52:00.995] Timer tick, asking Buildbucket for the build status
[17:52:01.242] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:01.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:96:0) after 1m0s
[17:53:01.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:96:0)
[17:53:01.279] Timer tick, asking Buildbucket for the build status
[17:53:01.391] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:01.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:97:0) after 1m0s
[17:54:01.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:97:0)
[17:54:01.372] Timer tick, asking Buildbucket for the build status
[17:54:01.563] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:01.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:98:0) after 1m0s
[17:55:01.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:98:0)
[17:55:01.592] Timer tick, asking Buildbucket for the build status
[17:55:01.797] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:01.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:99:0) after 1m0s
[17:56:01.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:99:0)
[17:56:01.820] Timer tick, asking Buildbucket for the build status
[17:56:01.903] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:01.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:100:0) after 1m0s
[17:57:01.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:100:0)
[17:57:01.898] Timer tick, asking Buildbucket for the build status
[17:57:02.038] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:02.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:101:0) after 1m0s
[17:58:02.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:101:0)
[17:58:02.075] Timer tick, asking Buildbucket for the build status
[17:58:02.147] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:02.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:102:0) after 1m0s
[17:59:02.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:102:0)
[17:59:02.188] Timer tick, asking Buildbucket for the build status
[17:59:02.370] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:02.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:103:0) after 1m0s
[18:00:02.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:103:0)
[18:00:02.342] Timer tick, asking Buildbucket for the build status
[18:00:02.487] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:02.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:104:0) after 1m0s
[18:01:02.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:104:0)
[18:01:02.510] Timer tick, asking Buildbucket for the build status
[18:01:02.722] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:02.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:105:0) after 1m0s
[18:02:02.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:105:0)
[18:02:02.785] Timer tick, asking Buildbucket for the build status
[18:02:02.872] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:02.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:106:0) after 1m0s
[18:03:02.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:106:0)
[18:03:02.887] Timer tick, asking Buildbucket for the build status
[18:03:03.131] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:03.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:107:0) after 1m0s
[18:04:03.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:107:0)
[18:04:03.169] Timer tick, asking Buildbucket for the build status
[18:04:03.492] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:03.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:108:0) after 1m0s
[18:05:03.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:108:0)
[18:05:03.535] Timer tick, asking Buildbucket for the build status
[18:05:03.837] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:03.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:109:0) after 1m0s
[18:06:03.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:109:0)
[18:06:03.836] Timer tick, asking Buildbucket for the build status
[18:06:03.913] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:03.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:110:0) after 1m0s
[18:07:03.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:110:0)
[18:07:03.937] Timer tick, asking Buildbucket for the build status
[18:07:04.083] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:04.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:111:0) after 1m0s
[18:08:04.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:111:0)
[18:08:04.112] Timer tick, asking Buildbucket for the build status
[18:08:04.149] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:04.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:112:0) after 1m0s
[18:09:04.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:112:0)
[18:09:04.171] Timer tick, asking Buildbucket for the build status
[18:09:04.861] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:04.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:113:0) after 1m0s
[18:10:04.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:113:0)
[18:10:04.884] Timer tick, asking Buildbucket for the build status
[18:10:05.156] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:05.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:114:0) after 1m0s
[18:11:05.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:114:0)
[18:11:05.179] Timer tick, asking Buildbucket for the build status
[18:11:05.253] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:05.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:115:0) after 1m0s
[18:12:05.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:115:0)
[18:12:05.331] Timer tick, asking Buildbucket for the build status
[18:12:05.417] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:05.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:116:0) after 1m0s
[18:13:05.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:116:0)
[18:13:05.442] Timer tick, asking Buildbucket for the build status
[18:13:05.606] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:05.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:117:0) after 1m0s
[18:14:05.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:117:0)
[18:14:05.623] Timer tick, asking Buildbucket for the build status
[18:14:05.733] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:05.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:118:0) after 1m0s
[18:15:05.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:118:0)
[18:15:05.732] Timer tick, asking Buildbucket for the build status
[18:15:05.863] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:05.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:119:0) after 1m0s
[18:16:05.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:119:0)
[18:16:05.886] Timer tick, asking Buildbucket for the build status
[18:16:06.249] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:06.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:120:0) after 1m0s
[18:17:06.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:120:0)
[18:17:06.275] Timer tick, asking Buildbucket for the build status
[18:17:06.404] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:06.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:121:0) after 1m0s
[18:18:06.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:121:0)
[18:18:06.383] Timer tick, asking Buildbucket for the build status
[18:18:06.480] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:06.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:122:0) after 1m0s
[18:19:06.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:122:0)
[18:19:06.470] Timer tick, asking Buildbucket for the build status
[18:19:06.566] Build 8918179875871726928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:06.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083609937918292336:123:0) after 1m0s
[18:19:37.704] Received PubSub notification, asking Buildbucket for the build status
[18:19:37.742] Build 8918179875871726928: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:19:37.742] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553365177370255",
"created_by": "project:fuchsia",
"created_ts": "1553357925498318",
"failure_reason": "BUILD_FAILURE",
"id": "8918179875871726928",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2e92846d365a95b1379ed12c351582b681ce3d99\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-c-4q7x\", \"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\": \"2e92846d365a95b1379ed12c351582b681ce3d99\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-c-4q7x\"], \"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\": [\"4216-cf4a3cd\"], \"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": "1553357946728115",
"status": "COMPLETED",
"status_changed_ts": "1553357947301969",
"tags": [
"builder:clang",
"buildset:commit/git/2e92846d365a95b1379ed12c351582b681ce3d99",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2e92846d365a95b1379ed12c351582b681ce3d99",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083609937918292336",
"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/8918179875871726928/+/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:43c42a7669ff4210",
"user_agent:luci-scheduler"
],
"updated_ts": "1553365177401874",
"url": "https://ci.chromium.org/b/8918179875871726928",
"utcnow_ts": "1553365177735523"
}
}
[18:19:37.742] Invocation finished in 2h0m53.580063624s with status FAILED