[03:21:48.268] New invocation is queued and will start shortly
[03:21:49.400] Starting the invocation (attempt 1)
[03:21:49.443] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:21:49.443] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:21:49.443] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083387027350788512",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"fd880d30b194a526cda07f5ca3218a71b42ae3ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083387027350788512",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"gitiles_ref:refs/heads/master"
]
}
[03:21:50.564] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553570509575693",
"id": "8917956965309667392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"fd880d30b194a526cda07f5ca3218a71b42ae3ee\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553570510345441",
"tags": [
"builder:clang",
"buildset:commit/git/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083387027350788512",
"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/8917956965309667392/+/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": "1553570510345619",
"url": "https://ci.chromium.org/b/8917956965309667392",
"utcnow_ts": "1553570510550233"
}
}
[03:21:50.564] Task URL: https://ci.chromium.org/b/8917956965309667392
[03:21:50.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:2:0) after 1m0s
[03:22:09.013] Received PubSub notification, asking Buildbucket for the build status
[03:22:09.068] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:50.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:2:0)
[03:22:50.567] Timer tick, asking Buildbucket for the build status
[03:22:50.754] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:50.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:4:0) after 1m0s
[03:23:50.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:4:0)
[03:23:50.772] Timer tick, asking Buildbucket for the build status
[03:23:51.082] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:51.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:5:0) after 1m0s
[03:24:51.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:5:0)
[03:24:51.110] Timer tick, asking Buildbucket for the build status
[03:24:51.190] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:51.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:6:0) after 1m0s
[03:25:51.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:6:0)
[03:25:51.211] Timer tick, asking Buildbucket for the build status
[03:25:51.464] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:51.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:7:0) after 1m0s
[03:26:51.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:7:0)
[03:26:51.548] Timer tick, asking Buildbucket for the build status
[03:26:51.603] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:51.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:8:0) after 1m0s
[03:27:51.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:8:0)
[03:27:51.620] Timer tick, asking Buildbucket for the build status
[03:27:51.884] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:51.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:9:0) after 1m0s
[03:28:51.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:9:0)
[03:28:51.881] Timer tick, asking Buildbucket for the build status
[03:28:52.078] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:52.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:10:0) after 1m0s
[03:29:52.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:10:0)
[03:29:52.121] Timer tick, asking Buildbucket for the build status
[03:29:52.178] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:52.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:11:0) after 1m0s
[03:30:52.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:11:0)
[03:30:52.201] Timer tick, asking Buildbucket for the build status
[03:30:52.385] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:52.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:12:0) after 1m0s
[03:31:52.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:12:0)
[03:31:52.406] Timer tick, asking Buildbucket for the build status
[03:31:52.458] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:52.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:13:0) after 1m0s
[03:32:52.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:13:0)
[03:32:52.475] Timer tick, asking Buildbucket for the build status
[03:32:52.637] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:52.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:14:0) after 1m0s
[03:33:52.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:14:0)
[03:33:52.655] Timer tick, asking Buildbucket for the build status
[03:33:52.873] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:52.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:15:0) after 1m0s
[03:34:52.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:15:0)
[03:34:52.873] Timer tick, asking Buildbucket for the build status
[03:34:52.965] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:52.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:16:0) after 1m0s
[03:35:52.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:16:0)
[03:35:52.965] Timer tick, asking Buildbucket for the build status
[03:35:53.384] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:53.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:17:0) after 1m0s
[03:36:53.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:17:0)
[03:36:53.387] Timer tick, asking Buildbucket for the build status
[03:36:53.457] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:53.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:18:0) after 1m0s
[03:37:53.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:18:0)
[03:37:53.534] Timer tick, asking Buildbucket for the build status
[03:37:53.794] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:53.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:19:0) after 1m0s
[03:38:53.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:19:0)
[03:38:53.782] Timer tick, asking Buildbucket for the build status
[03:38:53.836] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:53.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:20:0) after 1m0s
[03:39:53.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:20:0)
[03:39:53.828] Timer tick, asking Buildbucket for the build status
[03:39:53.892] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:53.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:21:0) after 1m0s
[03:40:53.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:21:0)
[03:40:53.925] Timer tick, asking Buildbucket for the build status
[03:40:54.096] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:54.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:22:0) after 1m0s
[03:41:54.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:22:0)
[03:41:54.083] Timer tick, asking Buildbucket for the build status
[03:41:54.179] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:54.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:23:0) after 1m0s
[03:42:54.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:23:0)
[03:42:54.197] Timer tick, asking Buildbucket for the build status
[03:42:54.258] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:54.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:24:0) after 1m0s
[03:43:54.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:24:0)
[03:43:54.237] Timer tick, asking Buildbucket for the build status
[03:43:54.410] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:54.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:25:0) after 1m0s
[03:44:54.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:25:0)
[03:44:54.420] Timer tick, asking Buildbucket for the build status
[03:44:54.464] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:54.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:26:0) after 1m0s
[03:45:54.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:26:0)
[03:45:54.489] Timer tick, asking Buildbucket for the build status
[03:45:54.529] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:54.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:27:0) after 1m0s
[03:46:54.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:27:0)
[03:46:54.505] Timer tick, asking Buildbucket for the build status
[03:46:54.571] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:54.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:28:0) after 1m0s
[03:47:54.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:28:0)
[03:47:54.593] Timer tick, asking Buildbucket for the build status
[03:47:54.882] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:54.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:29:0) after 1m0s
[03:48:54.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:29:0)
[03:48:54.866] Timer tick, asking Buildbucket for the build status
[03:48:54.909] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:54.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:30:0) after 1m0s
[03:49:54.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:30:0)
[03:49:54.884] Timer tick, asking Buildbucket for the build status
[03:49:54.946] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:54.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:31:0) after 1m0s
[03:50:54.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:31:0)
[03:50:54.964] Timer tick, asking Buildbucket for the build status
[03:50:55.188] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:55.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:32:0) after 1m0s
[03:51:55.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:32:0)
[03:51:55.206] Timer tick, asking Buildbucket for the build status
[03:51:55.259] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:55.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:33:0) after 1m0s
[03:52:55.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:33:0)
[03:52:55.259] Timer tick, asking Buildbucket for the build status
[03:52:55.454] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:55.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:34:0) after 1m0s
[03:53:55.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:34:0)
[03:53:55.486] Timer tick, asking Buildbucket for the build status
[03:53:55.962] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:55.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:35:0) after 1m0s
[03:54:55.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:35:0)
[03:54:55.981] Timer tick, asking Buildbucket for the build status
[03:54:56.104] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:56.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:36:0) after 1m0s
[03:55:56.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:36:0)
[03:55:56.093] Timer tick, asking Buildbucket for the build status
[03:55:56.202] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:56.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:37:0) after 1m0s
[03:56:56.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:37:0)
[03:56:56.222] Timer tick, asking Buildbucket for the build status
[03:56:56.321] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:56.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:38:0) after 1m0s
[03:57:56.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:38:0)
[03:57:56.344] Timer tick, asking Buildbucket for the build status
[03:57:56.555] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:56.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:39:0) after 1m0s
[03:58:56.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:39:0)
[03:58:56.574] Timer tick, asking Buildbucket for the build status
[03:58:56.628] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:56.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:40:0) after 1m0s
[03:59:56.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:40:0)
[03:59:56.695] Timer tick, asking Buildbucket for the build status
[03:59:56.919] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:56.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:41:0) after 1m0s
[04:00:56.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:41:0)
[04:00:56.946] Timer tick, asking Buildbucket for the build status
[04:00:57.096] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:57.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:42:0) after 1m0s
[04:01:57.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:42:0)
[04:01:57.116] Timer tick, asking Buildbucket for the build status
[04:01:57.179] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:57.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:43:0) after 1m0s
[04:02:57.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:43:0)
[04:02:57.200] Timer tick, asking Buildbucket for the build status
[04:02:57.291] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:57.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:44:0) after 1m0s
[04:03:57.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:44:0)
[04:03:57.310] Timer tick, asking Buildbucket for the build status
[04:03:57.405] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:57.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:45:0) after 1m0s
[04:04:57.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:45:0)
[04:04:57.422] Timer tick, asking Buildbucket for the build status
[04:04:57.606] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:57.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:46:0) after 1m0s
[04:05:57.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:46:0)
[04:05:57.628] Timer tick, asking Buildbucket for the build status
[04:05:57.761] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:57.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:47:0) after 1m0s
[04:06:57.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:47:0)
[04:06:57.852] Timer tick, asking Buildbucket for the build status
[04:06:58.059] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:58.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:48:0) after 1m0s
[04:07:58.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:48:0)
[04:07:58.084] Timer tick, asking Buildbucket for the build status
[04:07:58.168] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:58.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:49:0) after 1m0s
[04:08:58.162] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:49:0)
[04:08:58.162] Timer tick, asking Buildbucket for the build status
[04:08:58.243] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:58.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:50:0) after 1m0s
[04:09:58.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:50:0)
[04:09:58.268] Timer tick, asking Buildbucket for the build status
[04:09:58.465] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:58.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:51:0) after 1m0s
[04:10:58.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:51:0)
[04:10:58.502] Timer tick, asking Buildbucket for the build status
[04:10:58.576] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:58.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:52:0) after 1m0s
[04:11:58.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:52:0)
[04:11:58.593] Timer tick, asking Buildbucket for the build status
[04:11:58.676] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:58.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:53:0) after 1m0s
[04:12:58.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:53:0)
[04:12:58.697] Timer tick, asking Buildbucket for the build status
[04:12:58.753] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:58.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:54:0) after 1m0s
[04:13:58.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:54:0)
[04:13:58.732] Timer tick, asking Buildbucket for the build status
[04:13:58.789] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:58.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:55:0) after 1m0s
[04:14:58.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:55:0)
[04:14:58.807] Timer tick, asking Buildbucket for the build status
[04:14:58.863] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:58.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:56:0) after 1m0s
[04:15:58.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:56:0)
[04:15:58.881] Timer tick, asking Buildbucket for the build status
[04:15:59.173] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:59.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:57:0) after 1m0s
[04:16:59.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:57:0)
[04:16:59.198] Timer tick, asking Buildbucket for the build status
[04:16:59.268] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:59.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:58:0) after 1m0s
[04:17:59.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:58:0)
[04:17:59.290] Timer tick, asking Buildbucket for the build status
[04:17:59.633] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:59.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:59:0) after 1m0s
[04:18:59.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:59:0)
[04:18:59.653] Timer tick, asking Buildbucket for the build status
[04:18:59.732] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:59.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:60:0) after 1m0s
[04:19:59.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:60:0)
[04:19:59.750] Timer tick, asking Buildbucket for the build status
[04:19:59.899] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:59.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:61:0) after 1m0s
[04:20:59.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:61:0)
[04:20:59.919] Timer tick, asking Buildbucket for the build status
[04:21:00.230] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:00.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:62:0) after 1m0s
[04:22:00.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:62:0)
[04:22:00.251] Timer tick, asking Buildbucket for the build status
[04:22:00.316] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:00.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:63:0) after 1m0s
[04:23:00.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:63:0)
[04:23:00.312] Timer tick, asking Buildbucket for the build status
[04:23:00.576] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:00.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:64:0) after 1m0s
[04:24:00.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:64:0)
[04:24:00.596] Timer tick, asking Buildbucket for the build status
[04:24:00.670] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:00.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:65:0) after 1m0s
[04:25:00.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:65:0)
[04:25:00.704] Timer tick, asking Buildbucket for the build status
[04:25:00.895] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:00.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:66:0) after 1m0s
[04:26:00.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:66:0)
[04:26:00.928] Timer tick, asking Buildbucket for the build status
[04:26:01.264] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:01.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:67:0) after 1m0s
[04:27:01.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:67:0)
[04:27:01.242] Timer tick, asking Buildbucket for the build status
[04:27:01.603] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:01.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:68:0) after 1m0s
[04:28:01.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:68:0)
[04:28:01.623] Timer tick, asking Buildbucket for the build status
[04:28:01.689] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:01.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:69:0) after 1m0s
[04:29:01.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:69:0)
[04:29:01.707] Timer tick, asking Buildbucket for the build status
[04:29:02.082] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:02.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:70:0) after 1m0s
[04:30:02.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:70:0)
[04:30:02.087] Timer tick, asking Buildbucket for the build status
[04:30:02.151] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:02.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:71:0) after 1m0s
[04:31:02.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:71:0)
[04:31:02.151] Timer tick, asking Buildbucket for the build status
[04:31:02.254] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:02.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:72:0) after 1m0s
[04:32:02.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:72:0)
[04:32:02.331] Timer tick, asking Buildbucket for the build status
[04:32:02.391] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:02.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:73:0) after 1m0s
[04:33:02.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:73:0)
[04:33:02.379] Timer tick, asking Buildbucket for the build status
[04:33:02.500] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:02.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:74:0) after 1m0s
[04:34:02.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:74:0)
[04:34:02.520] Timer tick, asking Buildbucket for the build status
[04:34:02.589] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:02.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:75:0) after 1m0s
[04:35:02.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:75:0)
[04:35:02.612] Timer tick, asking Buildbucket for the build status
[04:35:02.793] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:02.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:76:0) after 1m0s
[04:36:02.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:76:0)
[04:36:02.819] Timer tick, asking Buildbucket for the build status
[04:36:03.052] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:03.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:77:0) after 1m0s
[04:37:03.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:77:0)
[04:37:03.091] Timer tick, asking Buildbucket for the build status
[04:37:03.377] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:03.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:78:0) after 1m0s
[04:38:03.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:78:0)
[04:38:03.387] Timer tick, asking Buildbucket for the build status
[04:38:03.449] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:03.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:79:0) after 1m0s
[04:39:03.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:79:0)
[04:39:03.470] Timer tick, asking Buildbucket for the build status
[04:39:03.535] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:03.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:80:0) after 1m0s
[04:40:03.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:80:0)
[04:40:03.556] Timer tick, asking Buildbucket for the build status
[04:40:03.715] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:03.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:81:0) after 1m0s
[04:41:03.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:81:0)
[04:41:03.736] Timer tick, asking Buildbucket for the build status
[04:41:03.835] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:03.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:82:0) after 1m0s
[04:42:03.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:82:0)
[04:42:03.883] Timer tick, asking Buildbucket for the build status
[04:42:03.924] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:03.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:83:0) after 1m0s
[04:43:03.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:83:0)
[04:43:03.939] Timer tick, asking Buildbucket for the build status
[04:43:04.015] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:04.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:84:0) after 1m0s
[04:44:04.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:84:0)
[04:44:04.037] Timer tick, asking Buildbucket for the build status
[04:44:04.102] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:04.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:85:0) after 1m0s
[04:45:04.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:85:0)
[04:45:04.222] Timer tick, asking Buildbucket for the build status
[04:45:04.404] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:04.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:86:0) after 1m0s
[04:46:04.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:86:0)
[04:46:04.382] Timer tick, asking Buildbucket for the build status
[04:46:04.779] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:04.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:87:0) after 1m0s
[04:47:04.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:87:0)
[04:47:04.801] Timer tick, asking Buildbucket for the build status
[04:47:04.870] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:04.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:88:0) after 1m0s
[04:48:04.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:88:0)
[04:48:04.911] Timer tick, asking Buildbucket for the build status
[04:48:05.171] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:05.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:89:0) after 1m0s
[04:49:05.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:89:0)
[04:49:05.194] Timer tick, asking Buildbucket for the build status
[04:49:05.312] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:05.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:90:0) after 1m0s
[04:50:05.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:90:0)
[04:50:05.305] Timer tick, asking Buildbucket for the build status
[04:50:05.483] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:05.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:91:0) after 1m0s
[04:51:05.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:91:0)
[04:51:05.505] Timer tick, asking Buildbucket for the build status
[04:51:05.607] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:05.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:92:0) after 1m0s
[04:52:05.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:92:0)
[04:52:05.580] Timer tick, asking Buildbucket for the build status
[04:52:05.884] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:05.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:93:0) after 1m0s
[04:53:05.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:93:0)
[04:53:05.902] Timer tick, asking Buildbucket for the build status
[04:53:05.970] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:05.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:94:0) after 1m0s
[04:54:06.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:94:0)
[04:54:06.001] Timer tick, asking Buildbucket for the build status
[04:54:06.129] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:06.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:95:0) after 1m0s
[04:55:06.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:95:0)
[04:55:06.151] Timer tick, asking Buildbucket for the build status
[04:55:06.364] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:06.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:96:0) after 1m0s
[04:56:06.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:96:0)
[04:56:06.385] Timer tick, asking Buildbucket for the build status
[04:56:06.470] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:06.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:97:0) after 1m0s
[04:57:06.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:97:0)
[04:57:06.483] Timer tick, asking Buildbucket for the build status
[04:57:06.575] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:06.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:98:0) after 1m0s
[04:58:06.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:98:0)
[04:58:06.596] Timer tick, asking Buildbucket for the build status
[04:58:06.655] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:06.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:99:0) after 1m0s
[04:59:06.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:99:0)
[04:59:06.677] Timer tick, asking Buildbucket for the build status
[04:59:06.788] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:06.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:100:0) after 1m0s
[05:00:06.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:100:0)
[05:00:06.812] Timer tick, asking Buildbucket for the build status
[05:00:06.958] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:06.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:101:0) after 1m0s
[05:01:06.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:101:0)
[05:01:06.976] Timer tick, asking Buildbucket for the build status
[05:01:07.042] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:07.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:102:0) after 1m0s
[05:02:07.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:102:0)
[05:02:07.037] Timer tick, asking Buildbucket for the build status
[05:02:07.088] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:07.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:103:0) after 1m0s
[05:03:07.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:103:0)
[05:03:07.117] Timer tick, asking Buildbucket for the build status
[05:03:07.174] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:07.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:104:0) after 1m0s
[05:04:07.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:104:0)
[05:04:07.196] Timer tick, asking Buildbucket for the build status
[05:04:07.341] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:07.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:105:0) after 1m0s
[05:05:07.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:105:0)
[05:05:07.368] Timer tick, asking Buildbucket for the build status
[05:05:07.899] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:07.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:106:0) after 1m0s
[05:06:07.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:106:0)
[05:06:07.893] Timer tick, asking Buildbucket for the build status
[05:06:08.055] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:08.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:107:0) after 1m0s
[05:07:08.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:107:0)
[05:07:08.046] Timer tick, asking Buildbucket for the build status
[05:07:08.124] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:08.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:108:0) after 1m0s
[05:08:08.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:108:0)
[05:08:08.146] Timer tick, asking Buildbucket for the build status
[05:08:08.585] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:08.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:109:0) after 1m0s
[05:09:08.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:109:0)
[05:09:08.564] Timer tick, asking Buildbucket for the build status
[05:09:08.679] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:08.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:110:0) after 1m0s
[05:10:08.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:110:0)
[05:10:08.700] Timer tick, asking Buildbucket for the build status
[05:10:08.961] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:08.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:111:0) after 1m0s
[05:11:09.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:111:0)
[05:11:09.031] Timer tick, asking Buildbucket for the build status
[05:11:09.099] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:09.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:112:0) after 1m0s
[05:12:09.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:112:0)
[05:12:09.122] Timer tick, asking Buildbucket for the build status
[05:12:09.194] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:09.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:113:0) after 1m0s
[05:13:09.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:113:0)
[05:13:09.217] Timer tick, asking Buildbucket for the build status
[05:13:09.307] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:114:0) after 1m0s
[05:14:09.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:114:0)
[05:14:09.327] Timer tick, asking Buildbucket for the build status
[05:14:09.478] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:115:0) after 1m0s
[05:15:09.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:115:0)
[05:15:09.504] Timer tick, asking Buildbucket for the build status
[05:15:09.681] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:116:0) after 1m0s
[05:16:09.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:116:0)
[05:16:09.706] Timer tick, asking Buildbucket for the build status
[05:16:09.795] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:117:0) after 1m0s
[05:17:09.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:117:0)
[05:17:09.769] Timer tick, asking Buildbucket for the build status
[05:17:09.843] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:09.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:118:0) after 1m0s
[05:18:09.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:118:0)
[05:18:09.881] Timer tick, asking Buildbucket for the build status
[05:18:09.943] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:09.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:119:0) after 1m0s
[05:19:09.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:119:0)
[05:19:09.962] Timer tick, asking Buildbucket for the build status
[05:19:10.163] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:120:0) after 1m0s
[05:20:10.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:120:0)
[05:20:10.186] Timer tick, asking Buildbucket for the build status
[05:20:10.294] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:121:0) after 1m0s
[05:21:10.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:121:0)
[05:21:10.288] Timer tick, asking Buildbucket for the build status
[05:21:10.504] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:10.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:122:0) after 1m0s
[05:22:10.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:122:0)
[05:22:10.534] Timer tick, asking Buildbucket for the build status
[05:22:10.577] Build 8917956965309667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:10.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083387027350788512:123:0) after 1m0s
[05:22:39.321] Received PubSub notification, asking Buildbucket for the build status
[05:22:39.373] Build 8917956965309667392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:22:39.373] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553577759089807",
"created_by": "project:fuchsia",
"created_ts": "1553570509575693",
"failure_reason": "BUILD_FAILURE",
"id": "8917956965309667392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"fd880d30b194a526cda07f5ca3218a71b42ae3ee\"}}",
"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-40nw\", \"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\": \"fd880d30b194a526cda07f5ca3218a71b42ae3ee\", \"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-40nw\"], \"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": "1553570528534076",
"status": "COMPLETED",
"status_changed_ts": "1553570528832832",
"tags": [
"builder:clang",
"buildset:commit/git/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fd880d30b194a526cda07f5ca3218a71b42ae3ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083387027350788512",
"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/8917956965309667392/+/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:43d0d640b2f06010",
"user_agent:luci-scheduler"
],
"updated_ts": "1553577759145175",
"url": "https://ci.chromium.org/b/8917956965309667392",
"utcnow_ts": "1553577759359772"
}
}
[05:22:39.373] Invocation finished in 2h0m51.128152153s with status FAILED