[01:39:30.075] New invocation is queued and will start shortly
[01:39:31.151] Starting the invocation (attempt 1)
[01:39:31.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:31.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:31.188] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081490927411970624",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"263264345485263e318f3acc90b2ad90a8847b39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081490927411970624",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/263264345485263e318f3acc90b2ad90a8847b39",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/263264345485263e318f3acc90b2ad90a8847b39",
"gitiles_ref:refs/heads/master"
]
}
[01:39:32.045] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555378771447677",
"id": "8916060865309102080",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"263264345485263e318f3acc90b2ad90a8847b39\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555378771784025",
"tags": [
"builder:clang",
"buildset:commit/git/263264345485263e318f3acc90b2ad90a8847b39",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/263264345485263e318f3acc90b2ad90a8847b39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081490927411970624",
"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/8916060865309102080/+/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": "1555378771784147",
"url": "https://ci.chromium.org/b/8916060865309102080",
"utcnow_ts": "1555378772036133"
}
}
[01:39:32.045] Task URL: https://ci.chromium.org/b/8916060865309102080
[01:39:32.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:2:0) after 1m0s
[01:39:51.101] Received PubSub notification, asking Buildbucket for the build status
[01:39:51.200] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:32.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:2:0)
[01:40:32.063] Timer tick, asking Buildbucket for the build status
[01:40:32.157] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:32.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:4:0) after 1m0s
[01:41:32.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:4:0)
[01:41:32.166] Timer tick, asking Buildbucket for the build status
[01:41:32.328] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:32.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:5:0) after 1m0s
[01:42:32.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:5:0)
[01:42:32.334] Timer tick, asking Buildbucket for the build status
[01:42:32.414] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:32.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:6:0) after 1m0s
[01:43:32.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:6:0)
[01:43:32.434] Timer tick, asking Buildbucket for the build status
[01:43:32.490] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:32.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:7:0) after 1m0s
[01:44:32.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:7:0)
[01:44:32.480] Timer tick, asking Buildbucket for the build status
[01:44:32.588] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:32.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:8:0) after 1m0s
[01:45:32.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:8:0)
[01:45:32.608] Timer tick, asking Buildbucket for the build status
[01:45:32.665] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:32.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:9:0) after 1m0s
[01:46:32.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:9:0)
[01:46:32.683] Timer tick, asking Buildbucket for the build status
[01:46:32.722] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:32.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:10:0) after 1m0s
[01:47:32.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:10:0)
[01:47:32.741] Timer tick, asking Buildbucket for the build status
[01:47:32.806] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:11:0) after 1m0s
[01:48:32.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:11:0)
[01:48:32.825] Timer tick, asking Buildbucket for the build status
[01:48:32.881] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:32.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:12:0) after 1m0s
[01:49:32.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:12:0)
[01:49:32.884] Timer tick, asking Buildbucket for the build status
[01:49:32.961] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:32.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:13:0) after 1m0s
[01:50:32.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:13:0)
[01:50:32.963] Timer tick, asking Buildbucket for the build status
[01:50:33.020] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:33.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:14:0) after 1m0s
[01:51:33.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:14:0)
[01:51:33.137] Timer tick, asking Buildbucket for the build status
[01:51:33.214] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:33.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:15:0) after 1m0s
[01:52:33.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:15:0)
[01:52:33.236] Timer tick, asking Buildbucket for the build status
[01:52:33.272] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:33.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:16:0) after 1m0s
[01:53:33.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:16:0)
[01:53:33.295] Timer tick, asking Buildbucket for the build status
[01:53:33.354] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:33.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:17:0) after 1m0s
[01:54:33.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:17:0)
[01:54:33.378] Timer tick, asking Buildbucket for the build status
[01:54:33.420] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:33.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:18:0) after 1m0s
[01:55:33.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:18:0)
[01:55:33.443] Timer tick, asking Buildbucket for the build status
[01:55:33.513] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:33.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:19:0) after 1m0s
[01:56:33.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:19:0)
[01:56:33.522] Timer tick, asking Buildbucket for the build status
[01:56:33.563] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:33.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:20:0) after 1m0s
[01:57:33.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:20:0)
[01:57:33.581] Timer tick, asking Buildbucket for the build status
[01:57:33.634] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:33.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:21:0) after 1m0s
[01:58:33.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:21:0)
[01:58:33.653] Timer tick, asking Buildbucket for the build status
[01:58:33.718] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:33.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:22:0) after 1m0s
[01:59:33.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:22:0)
[01:59:33.706] Timer tick, asking Buildbucket for the build status
[01:59:33.748] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:33.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:23:0) after 1m0s
[02:00:33.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:23:0)
[02:00:33.738] Timer tick, asking Buildbucket for the build status
[02:00:33.980] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:33.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:24:0) after 1m0s
[02:01:34.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:24:0)
[02:01:34.000] Timer tick, asking Buildbucket for the build status
[02:01:34.112] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:34.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:25:0) after 1m0s
[02:02:34.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:25:0)
[02:02:34.128] Timer tick, asking Buildbucket for the build status
[02:02:34.186] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:34.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:26:0) after 1m0s
[02:03:34.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:26:0)
[02:03:34.195] Timer tick, asking Buildbucket for the build status
[02:03:34.373] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:34.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:27:0) after 1m0s
[02:04:34.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:27:0)
[02:04:34.358] Timer tick, asking Buildbucket for the build status
[02:04:34.402] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:34.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:28:0) after 1m0s
[02:05:34.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:28:0)
[02:05:34.390] Timer tick, asking Buildbucket for the build status
[02:05:34.458] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:34.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:29:0) after 1m0s
[02:06:34.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:29:0)
[02:06:34.436] Timer tick, asking Buildbucket for the build status
[02:06:34.883] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:34.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:30:0) after 1m0s
[02:07:34.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:30:0)
[02:07:34.864] Timer tick, asking Buildbucket for the build status
[02:07:34.926] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:34.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:31:0) after 1m0s
[02:08:34.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:31:0)
[02:08:34.948] Timer tick, asking Buildbucket for the build status
[02:08:35.072] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:35.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:32:0) after 1m0s
[02:09:35.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:32:0)
[02:09:35.071] Timer tick, asking Buildbucket for the build status
[02:09:35.109] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:35.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:33:0) after 1m0s
[02:10:35.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:33:0)
[02:10:35.145] Timer tick, asking Buildbucket for the build status
[02:10:35.181] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:35.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:34:0) after 1m0s
[02:11:35.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:34:0)
[02:11:35.202] Timer tick, asking Buildbucket for the build status
[02:11:35.352] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:35.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:35:0) after 1m0s
[02:12:35.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:35:0)
[02:12:35.370] Timer tick, asking Buildbucket for the build status
[02:12:35.454] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:35.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:36:0) after 1m0s
[02:13:35.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:36:0)
[02:13:35.451] Timer tick, asking Buildbucket for the build status
[02:13:35.517] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:35.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:37:0) after 1m0s
[02:14:35.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:37:0)
[02:14:35.518] Timer tick, asking Buildbucket for the build status
[02:14:35.573] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:35.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:38:0) after 1m0s
[02:15:35.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:38:0)
[02:15:35.675] Timer tick, asking Buildbucket for the build status
[02:15:35.728] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:35.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:39:0) after 1m0s
[02:16:35.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:39:0)
[02:16:35.810] Timer tick, asking Buildbucket for the build status
[02:16:36.043] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:36.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:40:0) after 1m0s
[02:17:36.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:40:0)
[02:17:36.065] Timer tick, asking Buildbucket for the build status
[02:17:36.107] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:36.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:41:0) after 1m0s
[02:18:36.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:41:0)
[02:18:36.129] Timer tick, asking Buildbucket for the build status
[02:18:36.210] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:36.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:42:0) after 1m0s
[02:19:36.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:42:0)
[02:19:36.213] Timer tick, asking Buildbucket for the build status
[02:19:36.396] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:36.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:43:0) after 1m0s
[02:20:36.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:43:0)
[02:20:36.415] Timer tick, asking Buildbucket for the build status
[02:20:36.672] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:36.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:44:0) after 1m0s
[02:21:36.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:44:0)
[02:21:36.688] Timer tick, asking Buildbucket for the build status
[02:21:36.726] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:36.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:45:0) after 1m0s
[02:22:36.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:45:0)
[02:22:36.747] Timer tick, asking Buildbucket for the build status
[02:22:36.869] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:36.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:46:0) after 1m0s
[02:23:36.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:46:0)
[02:23:36.879] Timer tick, asking Buildbucket for the build status
[02:23:36.942] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:36.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:47:0) after 1m0s
[02:24:36.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:47:0)
[02:24:36.929] Timer tick, asking Buildbucket for the build status
[02:24:37.295] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:37.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:48:0) after 1m0s
[02:25:37.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:48:0)
[02:25:37.303] Timer tick, asking Buildbucket for the build status
[02:25:37.378] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:37.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:49:0) after 1m0s
[02:26:37.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:49:0)
[02:26:37.398] Timer tick, asking Buildbucket for the build status
[02:26:37.697] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:37.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:50:0) after 1m0s
[02:27:37.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:50:0)
[02:27:37.704] Timer tick, asking Buildbucket for the build status
[02:27:37.737] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:37.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:51:0) after 1m0s
[02:28:37.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:51:0)
[02:28:37.791] Timer tick, asking Buildbucket for the build status
[02:28:37.886] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:37.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:52:0) after 1m0s
[02:29:37.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:52:0)
[02:29:37.910] Timer tick, asking Buildbucket for the build status
[02:29:37.973] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:37.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:53:0) after 1m0s
[02:30:38.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:53:0)
[02:30:38.004] Timer tick, asking Buildbucket for the build status
[02:30:38.064] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:38.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:54:0) after 1m0s
[02:31:38.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:54:0)
[02:31:38.066] Timer tick, asking Buildbucket for the build status
[02:31:38.361] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:38.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:55:0) after 1m0s
[02:32:38.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:55:0)
[02:32:38.368] Timer tick, asking Buildbucket for the build status
[02:32:38.439] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:38.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:56:0) after 1m0s
[02:33:38.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:56:0)
[02:33:38.483] Timer tick, asking Buildbucket for the build status
[02:33:38.569] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:38.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:57:0) after 1m0s
[02:34:38.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:57:0)
[02:34:38.589] Timer tick, asking Buildbucket for the build status
[02:34:38.654] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:38.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:58:0) after 1m0s
[02:35:38.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:58:0)
[02:35:38.695] Timer tick, asking Buildbucket for the build status
[02:35:38.824] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:38.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:59:0) after 1m0s
[02:36:38.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:59:0)
[02:36:38.845] Timer tick, asking Buildbucket for the build status
[02:36:38.982] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:38.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:60:0) after 1m0s
[02:37:39.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:60:0)
[02:37:39.007] Timer tick, asking Buildbucket for the build status
[02:37:39.091] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:39.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:61:0) after 1m0s
[02:38:39.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:61:0)
[02:38:39.088] Timer tick, asking Buildbucket for the build status
[02:38:39.351] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:39.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:62:0) after 1m0s
[02:39:39.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:62:0)
[02:39:39.372] Timer tick, asking Buildbucket for the build status
[02:39:39.527] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:39.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:63:0) after 1m0s
[02:40:39.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:63:0)
[02:40:39.526] Timer tick, asking Buildbucket for the build status
[02:40:39.782] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:39.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:64:0) after 1m0s
[02:41:39.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:64:0)
[02:41:39.758] Timer tick, asking Buildbucket for the build status
[02:41:39.893] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:39.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:65:0) after 1m0s
[02:42:39.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:65:0)
[02:42:39.913] Timer tick, asking Buildbucket for the build status
[02:42:39.959] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:39.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:66:0) after 1m0s
[02:43:39.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:66:0)
[02:43:39.951] Timer tick, asking Buildbucket for the build status
[02:43:40.238] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:40.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:67:0) after 1m0s
[02:44:40.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:67:0)
[02:44:40.369] Timer tick, asking Buildbucket for the build status
[02:44:40.490] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:40.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:68:0) after 1m0s
[02:45:40.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:68:0)
[02:45:40.509] Timer tick, asking Buildbucket for the build status
[02:45:40.791] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:40.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:69:0) after 1m0s
[02:46:40.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:69:0)
[02:46:40.813] Timer tick, asking Buildbucket for the build status
[02:46:40.883] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:40.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:70:0) after 1m0s
[02:47:40.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:70:0)
[02:47:40.905] Timer tick, asking Buildbucket for the build status
[02:47:40.956] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:40.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:71:0) after 1m0s
[02:48:40.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:71:0)
[02:48:40.977] Timer tick, asking Buildbucket for the build status
[02:48:41.170] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:41.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:72:0) after 1m0s
[02:49:41.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:72:0)
[02:49:41.193] Timer tick, asking Buildbucket for the build status
[02:49:41.290] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:41.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:73:0) after 1m0s
[02:50:41.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:73:0)
[02:50:41.311] Timer tick, asking Buildbucket for the build status
[02:50:41.461] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:41.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:74:0) after 1m0s
[02:51:41.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:74:0)
[02:51:41.482] Timer tick, asking Buildbucket for the build status
[02:51:41.553] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:41.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:75:0) after 1m0s
[02:52:41.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:75:0)
[02:52:41.530] Timer tick, asking Buildbucket for the build status
[02:52:41.654] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:41.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:76:0) after 1m0s
[02:53:41.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:76:0)
[02:53:41.682] Timer tick, asking Buildbucket for the build status
[02:53:41.948] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:41.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:77:0) after 1m0s
[02:54:41.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:77:0)
[02:54:41.971] Timer tick, asking Buildbucket for the build status
[02:54:42.157] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:42.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:78:0) after 1m0s
[02:55:42.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:78:0)
[02:55:42.148] Timer tick, asking Buildbucket for the build status
[02:55:42.213] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:42.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:79:0) after 1m0s
[02:56:42.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:79:0)
[02:56:42.269] Timer tick, asking Buildbucket for the build status
[02:56:42.347] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:42.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:80:0) after 1m0s
[02:57:42.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:80:0)
[02:57:42.371] Timer tick, asking Buildbucket for the build status
[02:57:42.425] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:42.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:81:0) after 1m0s
[02:58:42.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:81:0)
[02:58:42.445] Timer tick, asking Buildbucket for the build status
[02:58:42.484] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:42.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:82:0) after 1m0s
[02:59:42.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:82:0)
[02:59:42.523] Timer tick, asking Buildbucket for the build status
[02:59:42.570] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:42.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:83:0) after 1m0s
[03:00:42.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:83:0)
[03:00:42.595] Timer tick, asking Buildbucket for the build status
[03:00:42.652] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:42.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:84:0) after 1m0s
[03:01:42.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:84:0)
[03:01:42.675] Timer tick, asking Buildbucket for the build status
[03:01:42.755] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:42.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:85:0) after 1m0s
[03:02:42.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:85:0)
[03:02:42.775] Timer tick, asking Buildbucket for the build status
[03:02:42.966] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:42.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:86:0) after 1m0s
[03:03:42.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:86:0)
[03:03:42.984] Timer tick, asking Buildbucket for the build status
[03:03:43.048] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:43.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:87:0) after 1m0s
[03:04:43.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:87:0)
[03:04:43.072] Timer tick, asking Buildbucket for the build status
[03:04:43.135] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:43.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:88:0) after 1m0s
[03:05:43.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:88:0)
[03:05:43.158] Timer tick, asking Buildbucket for the build status
[03:05:43.201] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:43.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:89:0) after 1m0s
[03:06:43.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:89:0)
[03:06:43.225] Timer tick, asking Buildbucket for the build status
[03:06:43.373] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:43.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:90:0) after 1m0s
[03:07:43.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:90:0)
[03:07:43.397] Timer tick, asking Buildbucket for the build status
[03:07:43.573] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:43.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:91:0) after 1m0s
[03:08:43.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:91:0)
[03:08:43.591] Timer tick, asking Buildbucket for the build status
[03:08:43.691] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:43.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:92:0) after 1m0s
[03:09:43.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:92:0)
[03:09:43.713] Timer tick, asking Buildbucket for the build status
[03:09:43.786] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:43.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:93:0) after 1m0s
[03:10:43.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:93:0)
[03:10:43.808] Timer tick, asking Buildbucket for the build status
[03:10:44.164] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:44.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:94:0) after 1m0s
[03:11:44.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:94:0)
[03:11:44.189] Timer tick, asking Buildbucket for the build status
[03:11:44.250] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:44.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:95:0) after 1m0s
[03:12:44.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:95:0)
[03:12:44.227] Timer tick, asking Buildbucket for the build status
[03:12:44.290] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:44.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:96:0) after 1m0s
[03:13:44.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:96:0)
[03:13:44.276] Timer tick, asking Buildbucket for the build status
[03:13:44.489] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:44.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:97:0) after 1m0s
[03:14:44.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:97:0)
[03:14:44.510] Timer tick, asking Buildbucket for the build status
[03:14:44.553] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:44.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:98:0) after 1m0s
[03:15:44.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:98:0)
[03:15:44.597] Timer tick, asking Buildbucket for the build status
[03:15:44.682] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:44.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:99:0) after 1m0s
[03:16:44.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:99:0)
[03:16:44.703] Timer tick, asking Buildbucket for the build status
[03:16:44.862] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:44.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:100:0) after 1m0s
[03:17:44.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:100:0)
[03:17:44.839] Timer tick, asking Buildbucket for the build status
[03:17:44.977] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:44.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:101:0) after 1m0s
[03:18:44.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:101:0)
[03:18:44.986] Timer tick, asking Buildbucket for the build status
[03:18:45.030] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:45.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:102:0) after 1m0s
[03:19:45.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:102:0)
[03:19:45.079] Timer tick, asking Buildbucket for the build status
[03:19:45.145] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:45.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:103:0) after 1m0s
[03:20:45.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:103:0)
[03:20:45.168] Timer tick, asking Buildbucket for the build status
[03:20:45.287] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:45.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:104:0) after 1m0s
[03:21:45.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:104:0)
[03:21:45.272] Timer tick, asking Buildbucket for the build status
[03:21:45.310] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:45.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:105:0) after 1m0s
[03:22:45.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:105:0)
[03:22:45.326] Timer tick, asking Buildbucket for the build status
[03:22:45.664] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:45.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:106:0) after 1m0s
[03:23:45.681] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:106:0)
[03:23:45.681] Timer tick, asking Buildbucket for the build status
[03:23:45.794] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:45.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:107:0) after 1m0s
[03:24:45.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:107:0)
[03:24:45.783] Timer tick, asking Buildbucket for the build status
[03:24:45.859] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:45.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:108:0) after 1m0s
[03:25:45.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:108:0)
[03:25:45.878] Timer tick, asking Buildbucket for the build status
[03:25:45.918] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:45.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:109:0) after 1m0s
[03:26:45.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:109:0)
[03:26:45.938] Timer tick, asking Buildbucket for the build status
[03:26:46.130] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:46.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:110:0) after 1m0s
[03:27:46.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:110:0)
[03:27:46.110] Timer tick, asking Buildbucket for the build status
[03:27:46.170] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:46.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:111:0) after 1m0s
[03:28:46.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:111:0)
[03:28:46.187] Timer tick, asking Buildbucket for the build status
[03:28:46.304] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:46.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:112:0) after 1m0s
[03:29:46.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:112:0)
[03:29:46.348] Timer tick, asking Buildbucket for the build status
[03:29:46.396] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:46.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:113:0) after 1m0s
[03:30:46.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:113:0)
[03:30:46.394] Timer tick, asking Buildbucket for the build status
[03:30:46.557] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:46.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:114:0) after 1m0s
[03:31:46.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:114:0)
[03:31:46.584] Timer tick, asking Buildbucket for the build status
[03:31:46.639] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:46.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:115:0) after 1m0s
[03:32:46.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:115:0)
[03:32:46.663] Timer tick, asking Buildbucket for the build status
[03:32:46.718] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:46.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:116:0) after 1m0s
[03:33:46.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:116:0)
[03:33:46.740] Timer tick, asking Buildbucket for the build status
[03:33:46.779] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:46.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:117:0) after 1m0s
[03:34:46.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:117:0)
[03:34:46.765] Timer tick, asking Buildbucket for the build status
[03:34:46.816] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:46.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:118:0) after 1m0s
[03:35:46.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:118:0)
[03:35:46.837] Timer tick, asking Buildbucket for the build status
[03:35:46.970] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:46.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:119:0) after 1m0s
[03:36:47.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:119:0)
[03:36:47.019] Timer tick, asking Buildbucket for the build status
[03:36:47.185] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:47.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:120:0) after 1m0s
[03:37:47.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:120:0)
[03:37:47.164] Timer tick, asking Buildbucket for the build status
[03:37:47.268] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:47.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:121:0) after 1m0s
[03:38:47.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:121:0)
[03:38:47.328] Timer tick, asking Buildbucket for the build status
[03:38:47.491] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:47.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:122:0) after 1m0s
[03:39:47.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:122:0)
[03:39:47.475] Timer tick, asking Buildbucket for the build status
[03:39:47.536] Build 8916060865309102080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:47.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081490927411970624:123:0) after 1m0s
[03:40:07.036] Received PubSub notification, asking Buildbucket for the build status
[03:40:07.084] Build 8916060865309102080: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:40:07.084] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1555386006662841",
"created_by": "project:fuchsia",
"created_ts": "1555378771447677",
"failure_reason": "BUILD_FAILURE",
"id": "8916060865309102080",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"263264345485263e318f3acc90b2ad90a8847b39\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-7z8w\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"263264345485263e318f3acc90b2ad90a8847b39\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-7z8w\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4256-d1e1f70\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555378790604637",
"status": "COMPLETED",
"status_changed_ts": "1555378790894333",
"tags": [
"builder:clang",
"buildset:commit/git/263264345485263e318f3acc90b2ad90a8847b39",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/263264345485263e318f3acc90b2ad90a8847b39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081490927411970624",
"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/8916060865309102080/+/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:443c9e24a3aab910",
"user_agent:luci-scheduler"
],
"updated_ts": "1555386006773980",
"url": "https://ci.chromium.org/b/8916060865309102080",
"utcnow_ts": "1555386007074034"
}
}
[03:40:07.084] Invocation finished in 2h0m37.025826544s with status FAILED