[04:04:53.464] New invocation is queued and will start shortly
[04:04:54.537] Starting the invocation (attempt 1)
[04:04:54.572] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:04:54.572] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:04:54.572] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080213422757484000",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"d43b66b3187c28c765bcfbaa159e8e805dd74519\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080213422757484000",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"gitiles_ref:refs/heads/master"
]
}
[04:04:55.278] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556597094650265",
"id": "8914783360838310896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"d43b66b3187c28c765bcfbaa159e8e805dd74519\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556597095077027",
"tags": [
"builder:clang",
"buildset:commit/git/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080213422757484000",
"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/8914783360838310896/+/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": "1556597095077140",
"url": "https://ci.chromium.org/b/8914783360838310896",
"utcnow_ts": "1556597095264259"
}
}
[04:04:55.278] Task URL: https://ci.chromium.org/b/8914783360838310896
[04:04:55.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:2:0) after 1m0s
[04:05:37.512] Received PubSub notification, asking Buildbucket for the build status
[04:05:37.566] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:2:0)
[04:05:55.249] Timer tick, asking Buildbucket for the build status
[04:05:55.473] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:4:0) after 1m0s
[04:06:55.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:4:0)
[04:06:55.492] Timer tick, asking Buildbucket for the build status
[04:06:55.559] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:55.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:5:0) after 1m0s
[04:07:55.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:5:0)
[04:07:55.682] Timer tick, asking Buildbucket for the build status
[04:07:55.749] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:55.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:6:0) after 1m0s
[04:08:55.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:6:0)
[04:08:55.769] Timer tick, asking Buildbucket for the build status
[04:08:55.885] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:55.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:7:0) after 1m0s
[04:09:55.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:7:0)
[04:09:55.871] Timer tick, asking Buildbucket for the build status
[04:09:55.923] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:55.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:8:0) after 1m0s
[04:10:55.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:8:0)
[04:10:55.888] Timer tick, asking Buildbucket for the build status
[04:10:55.964] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:55.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:9:0) after 1m0s
[04:11:55.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:9:0)
[04:11:55.957] Timer tick, asking Buildbucket for the build status
[04:11:56.029] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:56.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:10:0) after 1m0s
[04:12:56.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:10:0)
[04:12:56.059] Timer tick, asking Buildbucket for the build status
[04:12:56.114] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:56.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:11:0) after 1m0s
[04:13:56.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:11:0)
[04:13:56.133] Timer tick, asking Buildbucket for the build status
[04:13:56.275] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:56.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:12:0) after 1m0s
[04:14:56.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:12:0)
[04:14:56.299] Timer tick, asking Buildbucket for the build status
[04:14:56.391] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:56.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:13:0) after 1m0s
[04:15:56.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:13:0)
[04:15:56.414] Timer tick, asking Buildbucket for the build status
[04:15:56.498] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:56.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:14:0) after 1m0s
[04:16:56.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:14:0)
[04:16:56.514] Timer tick, asking Buildbucket for the build status
[04:16:56.599] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:56.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:15:0) after 1m0s
[04:17:56.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:15:0)
[04:17:56.579] Timer tick, asking Buildbucket for the build status
[04:17:56.626] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:56.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:16:0) after 1m0s
[04:18:56.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:16:0)
[04:18:56.662] Timer tick, asking Buildbucket for the build status
[04:18:56.717] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:56.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:17:0) after 1m0s
[04:19:56.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:17:0)
[04:19:56.743] Timer tick, asking Buildbucket for the build status
[04:19:56.790] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:56.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:18:0) after 1m0s
[04:20:56.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:18:0)
[04:20:56.810] Timer tick, asking Buildbucket for the build status
[04:20:56.875] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:56.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:19:0) after 1m0s
[04:21:56.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:19:0)
[04:21:56.887] Timer tick, asking Buildbucket for the build status
[04:21:56.982] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:20:0) after 1m0s
[04:22:56.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:20:0)
[04:22:56.986] Timer tick, asking Buildbucket for the build status
[04:22:57.054] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:57.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:21:0) after 1m0s
[04:23:57.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:21:0)
[04:23:57.073] Timer tick, asking Buildbucket for the build status
[04:23:57.276] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:57.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:22:0) after 1m0s
[04:24:57.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:22:0)
[04:24:57.294] Timer tick, asking Buildbucket for the build status
[04:24:57.370] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:57.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:23:0) after 1m0s
[04:25:57.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:23:0)
[04:25:57.386] Timer tick, asking Buildbucket for the build status
[04:25:57.569] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:57.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:24:0) after 1m0s
[04:26:57.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:24:0)
[04:26:57.597] Timer tick, asking Buildbucket for the build status
[04:26:57.901] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:25:0) after 1m0s
[04:27:57.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:25:0)
[04:27:57.891] Timer tick, asking Buildbucket for the build status
[04:27:57.947] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:26:0) after 1m0s
[04:28:57.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:26:0)
[04:28:57.970] Timer tick, asking Buildbucket for the build status
[04:28:58.086] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:58.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:27:0) after 1m0s
[04:29:58.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:27:0)
[04:29:58.105] Timer tick, asking Buildbucket for the build status
[04:29:58.204] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:28:0) after 1m0s
[04:30:58.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:28:0)
[04:30:58.221] Timer tick, asking Buildbucket for the build status
[04:30:58.608] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:29:0) after 1m0s
[04:31:58.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:29:0)
[04:31:58.620] Timer tick, asking Buildbucket for the build status
[04:31:58.954] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:30:0) after 1m0s
[04:32:58.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:30:0)
[04:32:58.961] Timer tick, asking Buildbucket for the build status
[04:32:59.173] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:59.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:31:0) after 1m0s
[04:33:59.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:31:0)
[04:33:59.179] Timer tick, asking Buildbucket for the build status
[04:33:59.416] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:32:0) after 1m0s
[04:34:59.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:32:0)
[04:34:59.411] Timer tick, asking Buildbucket for the build status
[04:34:59.476] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:33:0) after 1m0s
[04:35:59.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:33:0)
[04:35:59.512] Timer tick, asking Buildbucket for the build status
[04:35:59.569] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:34:0) after 1m0s
[04:36:59.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:34:0)
[04:36:59.582] Timer tick, asking Buildbucket for the build status
[04:36:59.665] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:35:0) after 1m0s
[04:37:59.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:35:0)
[04:37:59.682] Timer tick, asking Buildbucket for the build status
[04:37:59.791] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:36:0) after 1m0s
[04:38:59.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:36:0)
[04:38:59.814] Timer tick, asking Buildbucket for the build status
[04:38:59.865] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:37:0) after 1m0s
[04:39:59.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:37:0)
[04:39:59.833] Timer tick, asking Buildbucket for the build status
[04:39:59.891] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:38:0) after 1m0s
[04:40:59.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:38:0)
[04:40:59.873] Timer tick, asking Buildbucket for the build status
[04:40:59.915] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:59.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:39:0) after 1m0s
[04:41:59.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:39:0)
[04:41:59.934] Timer tick, asking Buildbucket for the build status
[04:42:00.101] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:40:0) after 1m0s
[04:43:00.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:40:0)
[04:43:00.123] Timer tick, asking Buildbucket for the build status
[04:43:00.184] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:00.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:41:0) after 1m0s
[04:44:00.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:41:0)
[04:44:00.180] Timer tick, asking Buildbucket for the build status
[04:44:00.248] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:00.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:42:0) after 1m0s
[04:45:00.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:42:0)
[04:45:00.266] Timer tick, asking Buildbucket for the build status
[04:45:00.331] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:00.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:43:0) after 1m0s
[04:46:00.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:43:0)
[04:46:00.351] Timer tick, asking Buildbucket for the build status
[04:46:00.420] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:00.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:44:0) after 1m0s
[04:47:00.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:44:0)
[04:47:00.509] Timer tick, asking Buildbucket for the build status
[04:47:00.684] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:00.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:45:0) after 1m0s
[04:48:00.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:45:0)
[04:48:00.709] Timer tick, asking Buildbucket for the build status
[04:48:00.805] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:00.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:46:0) after 1m0s
[04:49:00.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:46:0)
[04:49:00.830] Timer tick, asking Buildbucket for the build status
[04:49:00.898] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:00.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:47:0) after 1m0s
[04:50:00.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:47:0)
[04:50:00.905] Timer tick, asking Buildbucket for the build status
[04:50:01.195] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:01.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:48:0) after 1m0s
[04:51:01.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:48:0)
[04:51:01.343] Timer tick, asking Buildbucket for the build status
[04:51:01.461] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:01.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:49:0) after 1m0s
[04:52:01.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:49:0)
[04:52:01.482] Timer tick, asking Buildbucket for the build status
[04:52:01.587] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:01.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:50:0) after 1m0s
[04:53:01.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:50:0)
[04:53:01.606] Timer tick, asking Buildbucket for the build status
[04:53:06.870] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:06.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:51:0) after 1m0s
[04:54:06.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:51:0)
[04:54:06.890] Timer tick, asking Buildbucket for the build status
[04:54:06.944] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:06.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:52:0) after 1m0s
[04:55:06.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:52:0)
[04:55:06.979] Timer tick, asking Buildbucket for the build status
[04:55:07.091] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:07.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:53:0) after 1m0s
[04:56:07.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:53:0)
[04:56:07.112] Timer tick, asking Buildbucket for the build status
[04:56:07.179] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:07.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:54:0) after 1m0s
[04:57:07.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:54:0)
[04:57:07.204] Timer tick, asking Buildbucket for the build status
[04:57:07.302] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:07.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:55:0) after 1m0s
[04:58:07.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:55:0)
[04:58:07.311] Timer tick, asking Buildbucket for the build status
[04:58:07.391] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:07.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:56:0) after 1m0s
[04:59:07.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:56:0)
[04:59:07.481] Timer tick, asking Buildbucket for the build status
[04:59:07.606] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:07.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:57:0) after 1m0s
[05:00:07.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:57:0)
[05:00:07.623] Timer tick, asking Buildbucket for the build status
[05:00:12.603] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:12.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:58:0) after 1m0s
[05:01:12.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:58:0)
[05:01:12.623] Timer tick, asking Buildbucket for the build status
[05:01:12.665] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:12.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:59:0) after 1m0s
[05:02:12.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:59:0)
[05:02:12.686] Timer tick, asking Buildbucket for the build status
[05:02:12.752] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:12.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:60:0) after 1m0s
[05:03:12.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:60:0)
[05:03:12.769] Timer tick, asking Buildbucket for the build status
[05:03:12.845] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:12.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:61:0) after 1m0s
[05:04:12.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:61:0)
[05:04:12.862] Timer tick, asking Buildbucket for the build status
[05:04:12.912] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:12.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:62:0) after 1m0s
[05:05:12.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:62:0)
[05:05:12.921] Timer tick, asking Buildbucket for the build status
[05:05:13.235] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:13.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:63:0) after 1m0s
[05:06:13.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:63:0)
[05:06:13.281] Timer tick, asking Buildbucket for the build status
[05:06:13.337] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:13.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:64:0) after 1m0s
[05:07:13.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:64:0)
[05:07:13.394] Timer tick, asking Buildbucket for the build status
[05:07:13.460] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:13.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:65:0) after 1m0s
[05:08:13.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:65:0)
[05:08:13.480] Timer tick, asking Buildbucket for the build status
[05:08:13.516] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:13.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:66:0) after 1m0s
[05:09:13.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:66:0)
[05:09:13.514] Timer tick, asking Buildbucket for the build status
[05:09:13.587] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:13.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:67:0) after 1m0s
[05:10:13.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:67:0)
[05:10:13.607] Timer tick, asking Buildbucket for the build status
[05:10:13.708] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:13.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:68:0) after 1m0s
[05:11:13.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:68:0)
[05:11:13.738] Timer tick, asking Buildbucket for the build status
[05:11:13.869] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:13.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:69:0) after 1m0s
[05:12:13.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:69:0)
[05:12:13.889] Timer tick, asking Buildbucket for the build status
[05:12:13.953] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:13.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:70:0) after 1m0s
[05:13:13.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:70:0)
[05:13:13.969] Timer tick, asking Buildbucket for the build status
[05:13:14.062] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:14.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:71:0) after 1m0s
[05:14:14.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:71:0)
[05:14:14.082] Timer tick, asking Buildbucket for the build status
[05:14:14.269] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:14.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:72:0) after 1m0s
[05:15:14.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:72:0)
[05:15:14.292] Timer tick, asking Buildbucket for the build status
[05:15:14.365] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:14.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:73:0) after 1m0s
[05:16:14.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:73:0)
[05:16:14.395] Timer tick, asking Buildbucket for the build status
[05:16:14.475] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:14.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:74:0) after 1m0s
[05:17:14.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:74:0)
[05:17:14.470] Timer tick, asking Buildbucket for the build status
[05:17:14.556] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:14.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:75:0) after 1m0s
[05:18:14.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:75:0)
[05:18:14.552] Timer tick, asking Buildbucket for the build status
[05:18:14.622] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:14.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:76:0) after 1m0s
[05:19:14.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:76:0)
[05:19:14.640] Timer tick, asking Buildbucket for the build status
[05:19:14.762] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:14.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:77:0) after 1m0s
[05:20:14.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:77:0)
[05:20:14.796] Timer tick, asking Buildbucket for the build status
[05:20:14.987] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:14.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:78:0) after 1m0s
[05:21:15.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:78:0)
[05:21:15.018] Timer tick, asking Buildbucket for the build status
[05:21:15.283] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:15.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:79:0) after 1m0s
[05:22:15.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:79:0)
[05:22:15.319] Timer tick, asking Buildbucket for the build status
[05:22:15.359] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:15.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:80:0) after 1m0s
[05:23:15.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:80:0)
[05:23:15.388] Timer tick, asking Buildbucket for the build status
[05:23:15.442] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:15.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:81:0) after 1m0s
[05:24:15.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:81:0)
[05:24:15.456] Timer tick, asking Buildbucket for the build status
[05:24:15.531] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:15.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:82:0) after 1m0s
[05:25:15.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:82:0)
[05:25:15.523] Timer tick, asking Buildbucket for the build status
[05:25:15.584] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:15.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:83:0) after 1m0s
[05:26:15.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:83:0)
[05:26:15.581] Timer tick, asking Buildbucket for the build status
[05:26:15.698] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:15.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:84:0) after 1m0s
[05:27:15.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:84:0)
[05:27:15.716] Timer tick, asking Buildbucket for the build status
[05:27:15.755] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:15.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:85:0) after 1m0s
[05:28:15.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:85:0)
[05:28:15.776] Timer tick, asking Buildbucket for the build status
[05:28:15.856] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:15.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:86:0) after 1m0s
[05:29:15.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:86:0)
[05:29:15.865] Timer tick, asking Buildbucket for the build status
[05:29:15.901] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:15.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:87:0) after 1m0s
[05:30:15.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:87:0)
[05:30:15.934] Timer tick, asking Buildbucket for the build status
[05:30:16.020] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:16.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:88:0) after 1m0s
[05:31:16.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:88:0)
[05:31:16.067] Timer tick, asking Buildbucket for the build status
[05:31:16.105] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:16.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:89:0) after 1m0s
[05:32:16.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:89:0)
[05:32:16.093] Timer tick, asking Buildbucket for the build status
[05:32:16.174] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:16.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:90:0) after 1m0s
[05:33:16.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:90:0)
[05:33:16.178] Timer tick, asking Buildbucket for the build status
[05:33:16.218] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:16.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:91:0) after 1m0s
[05:34:16.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:91:0)
[05:34:16.232] Timer tick, asking Buildbucket for the build status
[05:34:16.685] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:16.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:92:0) after 1m0s
[05:35:16.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:92:0)
[05:35:16.705] Timer tick, asking Buildbucket for the build status
[05:35:17.049] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:17.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:93:0) after 1m0s
[05:36:17.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:93:0)
[05:36:17.034] Timer tick, asking Buildbucket for the build status
[05:36:17.287] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:17.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:94:0) after 1m0s
[05:37:17.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:94:0)
[05:37:17.281] Timer tick, asking Buildbucket for the build status
[05:37:17.327] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:17.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:95:0) after 1m0s
[05:38:17.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:95:0)
[05:38:17.363] Timer tick, asking Buildbucket for the build status
[05:38:17.485] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:17.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:96:0) after 1m0s
[05:39:17.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:96:0)
[05:39:17.506] Timer tick, asking Buildbucket for the build status
[05:39:17.596] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:17.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:97:0) after 1m0s
[05:40:17.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:97:0)
[05:40:17.602] Timer tick, asking Buildbucket for the build status
[05:40:17.655] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:17.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:98:0) after 1m0s
[05:41:17.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:98:0)
[05:41:17.698] Timer tick, asking Buildbucket for the build status
[05:41:17.744] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:17.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:99:0) after 1m0s
[05:42:17.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:99:0)
[05:42:17.814] Timer tick, asking Buildbucket for the build status
[05:42:18.359] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:18.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:100:0) after 1m0s
[05:43:18.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:100:0)
[05:43:18.359] Timer tick, asking Buildbucket for the build status
[05:43:18.466] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:18.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:101:0) after 1m0s
[05:44:18.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:101:0)
[05:44:18.470] Timer tick, asking Buildbucket for the build status
[05:44:18.696] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:18.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:102:0) after 1m0s
[05:45:18.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:102:0)
[05:45:18.722] Timer tick, asking Buildbucket for the build status
[05:45:18.767] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:18.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:103:0) after 1m0s
[05:46:18.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:103:0)
[05:46:18.789] Timer tick, asking Buildbucket for the build status
[05:46:18.867] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:18.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:104:0) after 1m0s
[05:47:18.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:104:0)
[05:47:18.887] Timer tick, asking Buildbucket for the build status
[05:47:18.974] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:18.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:105:0) after 1m0s
[05:48:18.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:105:0)
[05:48:18.991] Timer tick, asking Buildbucket for the build status
[05:48:19.119] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:19.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:106:0) after 1m0s
[05:49:19.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:106:0)
[05:49:19.125] Timer tick, asking Buildbucket for the build status
[05:49:19.212] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:19.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:107:0) after 1m0s
[05:50:19.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:107:0)
[05:50:19.294] Timer tick, asking Buildbucket for the build status
[05:50:19.490] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:19.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:108:0) after 1m0s
[05:51:19.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:108:0)
[05:51:19.506] Timer tick, asking Buildbucket for the build status
[05:51:19.549] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:19.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:109:0) after 1m0s
[05:52:19.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:109:0)
[05:52:19.542] Timer tick, asking Buildbucket for the build status
[05:52:20.065] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:20.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:110:0) after 1m0s
[05:53:20.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:110:0)
[05:53:20.067] Timer tick, asking Buildbucket for the build status
[05:53:20.116] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:20.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:111:0) after 1m0s
[05:54:20.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:111:0)
[05:54:20.132] Timer tick, asking Buildbucket for the build status
[05:54:20.262] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:20.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:112:0) after 1m0s
[05:55:20.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:112:0)
[05:55:20.286] Timer tick, asking Buildbucket for the build status
[05:55:20.468] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:20.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:113:0) after 1m0s
[05:56:20.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:113:0)
[05:56:20.491] Timer tick, asking Buildbucket for the build status
[05:56:20.787] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:20.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:114:0) after 1m0s
[05:57:20.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:114:0)
[05:57:20.806] Timer tick, asking Buildbucket for the build status
[05:57:21.091] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:21.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:115:0) after 1m0s
[05:58:21.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:115:0)
[05:58:21.112] Timer tick, asking Buildbucket for the build status
[05:58:21.288] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:21.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:116:0) after 1m0s
[05:59:21.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:116:0)
[05:59:21.309] Timer tick, asking Buildbucket for the build status
[05:59:21.351] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:21.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:117:0) after 1m0s
[06:00:21.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:117:0)
[06:00:21.382] Timer tick, asking Buildbucket for the build status
[06:00:21.453] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:21.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:118:0) after 1m0s
[06:01:21.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:118:0)
[06:01:21.453] Timer tick, asking Buildbucket for the build status
[06:01:21.493] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:21.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:119:0) after 1m0s
[06:02:21.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:119:0)
[06:02:21.479] Timer tick, asking Buildbucket for the build status
[06:02:21.547] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:21.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:120:0) after 1m0s
[06:03:21.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:120:0)
[06:03:21.582] Timer tick, asking Buildbucket for the build status
[06:03:21.620] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:21.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:121:0) after 1m0s
[06:04:21.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:121:0)
[06:04:21.611] Timer tick, asking Buildbucket for the build status
[06:04:21.683] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:21.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:122:0) after 1m0s
[06:05:21.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:122:0)
[06:05:21.704] Timer tick, asking Buildbucket for the build status
[06:05:22.832] Build 8914783360838310896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:22.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080213422757484000:123:0) after 1m0s
[06:06:03.007] Received PubSub notification, asking Buildbucket for the build status
[06:06:03.426] Build 8914783360838310896: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:06:03.426] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556604362687137",
"created_by": "project:fuchsia",
"created_ts": "1556597094650265",
"failure_reason": "BUILD_FAILURE",
"id": "8914783360838310896",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"d43b66b3187c28c765bcfbaa159e8e805dd74519\"}}",
"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-003729e9-us-central1-c-2qtx\", \"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\": \"d43b66b3187c28c765bcfbaa159e8e805dd74519\", \"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-003729e9-us-central1-c-2qtx\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190426-c90e1d5-prod\"], \"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\": [\"4277-e1590cf\"], \"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": "1556597137116270",
"status": "COMPLETED",
"status_changed_ts": "1556597137316308",
"tags": [
"builder:clang",
"buildset:commit/git/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d43b66b3187c28c765bcfbaa159e8e805dd74519",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080213422757484000",
"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/8914783360838310896/+/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:44853c43f776d810",
"user_agent:luci-scheduler"
],
"updated_ts": "1556604362759365",
"url": "https://ci.chromium.org/b/8914783360838310896",
"utcnow_ts": "1556604363362848"
}
}
[06:06:03.426] Invocation finished in 2h1m9.989604648s with status FAILED