[10:54:12.184] New invocation is queued and will start shortly
[10:54:13.240] Starting the invocation (attempt 1)
[10:54:13.360] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:54:13.360] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:54:13.360] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082905580066001744",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a2ed448bf24fe22e9a1149c6fcb583c139079b22\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082905580066001744",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"gitiles_ref:refs/heads/master"
]
}
[10:54:15.081] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554029653568100",
"id": "8917475517938848352",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a2ed448bf24fe22e9a1149c6fcb583c139079b22\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554029654943018",
"tags": [
"builder:clang",
"buildset:commit/git/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082905580066001744",
"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/8917475517938848352/+/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": "1554029654943259",
"url": "https://ci.chromium.org/b/8917475517938848352",
"utcnow_ts": "1554029655072384"
}
}
[10:54:15.081] Task URL: https://ci.chromium.org/b/8917475517938848352
[10:54:15.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:2:0) after 1m0s
[10:54:31.134] Received PubSub notification, asking Buildbucket for the build status
[10:54:31.213] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:15.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:2:0)
[10:55:15.059] Timer tick, asking Buildbucket for the build status
[10:55:15.239] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:15.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:4:0) after 1m0s
[10:56:15.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:4:0)
[10:56:15.255] Timer tick, asking Buildbucket for the build status
[10:56:15.349] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:15.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:5:0) after 1m0s
[10:57:15.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:5:0)
[10:57:15.372] Timer tick, asking Buildbucket for the build status
[10:57:15.699] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:15.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:6:0) after 1m0s
[10:58:15.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:6:0)
[10:58:15.776] Timer tick, asking Buildbucket for the build status
[10:58:15.947] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:15.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:7:0) after 1m0s
[10:59:15.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:7:0)
[10:59:15.945] Timer tick, asking Buildbucket for the build status
[10:59:16.027] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:16.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:8:0) after 1m0s
[11:00:16.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:8:0)
[11:00:16.037] Timer tick, asking Buildbucket for the build status
[11:00:16.644] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:16.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:9:0) after 1m0s
[11:01:16.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:9:0)
[11:01:16.661] Timer tick, asking Buildbucket for the build status
[11:01:16.759] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:16.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:10:0) after 1m0s
[11:02:16.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:10:0)
[11:02:16.778] Timer tick, asking Buildbucket for the build status
[11:02:16.930] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:16.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:11:0) after 1m0s
[11:03:16.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:11:0)
[11:03:16.957] Timer tick, asking Buildbucket for the build status
[11:03:17.024] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:17.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:12:0) after 1m0s
[11:04:17.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:12:0)
[11:04:17.029] Timer tick, asking Buildbucket for the build status
[11:04:17.101] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:17.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:13:0) after 1m0s
[11:05:17.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:13:0)
[11:05:17.119] Timer tick, asking Buildbucket for the build status
[11:05:17.209] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:17.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:14:0) after 1m0s
[11:06:17.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:14:0)
[11:06:17.238] Timer tick, asking Buildbucket for the build status
[11:06:17.629] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:17.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:15:0) after 1m0s
[11:07:17.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:15:0)
[11:07:17.648] Timer tick, asking Buildbucket for the build status
[11:07:17.719] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:17.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:16:0) after 1m0s
[11:08:17.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:16:0)
[11:08:17.782] Timer tick, asking Buildbucket for the build status
[11:08:17.900] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:17.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:17:0) after 1m0s
[11:09:17.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:17:0)
[11:09:17.917] Timer tick, asking Buildbucket for the build status
[11:09:18.085] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:18.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:18:0) after 1m0s
[11:10:18.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:18:0)
[11:10:18.081] Timer tick, asking Buildbucket for the build status
[11:10:18.141] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:18.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:19:0) after 1m0s
[11:11:18.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:19:0)
[11:11:18.155] Timer tick, asking Buildbucket for the build status
[11:11:18.460] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:18.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:20:0) after 1m0s
[11:12:18.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:20:0)
[11:12:18.481] Timer tick, asking Buildbucket for the build status
[11:12:18.729] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:21:0) after 1m0s
[11:13:18.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:21:0)
[11:13:18.705] Timer tick, asking Buildbucket for the build status
[11:13:18.763] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:22:0) after 1m0s
[11:14:18.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:22:0)
[11:14:18.783] Timer tick, asking Buildbucket for the build status
[11:14:20.285] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:20.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:23:0) after 1m0s
[11:15:20.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:23:0)
[11:15:20.318] Timer tick, asking Buildbucket for the build status
[11:15:20.458] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:20.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:24:0) after 1m0s
[11:16:20.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:24:0)
[11:16:20.471] Timer tick, asking Buildbucket for the build status
[11:16:20.597] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:20.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:25:0) after 1m0s
[11:17:20.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:25:0)
[11:17:20.576] Timer tick, asking Buildbucket for the build status
[11:17:20.713] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:20.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:26:0) after 1m0s
[11:18:20.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:26:0)
[11:18:20.740] Timer tick, asking Buildbucket for the build status
[11:18:20.909] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:27:0) after 1m0s
[11:19:20.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:27:0)
[11:19:20.950] Timer tick, asking Buildbucket for the build status
[11:19:21.008] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:21.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:28:0) after 1m0s
[11:20:21.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:28:0)
[11:20:21.026] Timer tick, asking Buildbucket for the build status
[11:20:21.074] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:21.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:29:0) after 1m0s
[11:21:21.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:29:0)
[11:21:21.096] Timer tick, asking Buildbucket for the build status
[11:21:21.184] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:21.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:30:0) after 1m0s
[11:22:21.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:30:0)
[11:22:21.205] Timer tick, asking Buildbucket for the build status
[11:22:21.324] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:21.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:31:0) after 1m0s
[11:23:21.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:31:0)
[11:23:21.340] Timer tick, asking Buildbucket for the build status
[11:23:21.397] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:21.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:32:0) after 1m0s
[11:24:21.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:32:0)
[11:24:21.395] Timer tick, asking Buildbucket for the build status
[11:24:21.529] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:21.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:33:0) after 1m0s
[11:25:21.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:33:0)
[11:25:21.548] Timer tick, asking Buildbucket for the build status
[11:25:21.607] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:21.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:34:0) after 1m0s
[11:26:21.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:34:0)
[11:26:21.627] Timer tick, asking Buildbucket for the build status
[11:26:21.722] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:21.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:35:0) after 1m0s
[11:27:21.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:35:0)
[11:27:21.711] Timer tick, asking Buildbucket for the build status
[11:27:21.829] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:21.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:36:0) after 1m0s
[11:28:21.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:36:0)
[11:28:21.849] Timer tick, asking Buildbucket for the build status
[11:28:21.974] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:37:0) after 1m0s
[11:29:21.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:37:0)
[11:29:21.994] Timer tick, asking Buildbucket for the build status
[11:29:22.426] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:22.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:38:0) after 1m0s
[11:30:22.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:38:0)
[11:30:22.444] Timer tick, asking Buildbucket for the build status
[11:30:22.605] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:22.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:39:0) after 1m0s
[11:31:22.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:39:0)
[11:31:22.629] Timer tick, asking Buildbucket for the build status
[11:31:22.726] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:22.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:40:0) after 1m0s
[11:32:22.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:40:0)
[11:32:22.744] Timer tick, asking Buildbucket for the build status
[11:32:22.790] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:22.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:41:0) after 1m0s
[11:33:22.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:41:0)
[11:33:22.811] Timer tick, asking Buildbucket for the build status
[11:33:22.895] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:22.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:42:0) after 1m0s
[11:34:22.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:42:0)
[11:34:22.914] Timer tick, asking Buildbucket for the build status
[11:34:22.977] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:22.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:43:0) after 1m0s
[11:35:23.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:43:0)
[11:35:23.001] Timer tick, asking Buildbucket for the build status
[11:35:23.195] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:23.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:44:0) after 1m0s
[11:36:23.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:44:0)
[11:36:23.216] Timer tick, asking Buildbucket for the build status
[11:36:23.329] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:23.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:45:0) after 1m0s
[11:37:23.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:45:0)
[11:37:23.350] Timer tick, asking Buildbucket for the build status
[11:37:23.412] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:23.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:46:0) after 1m0s
[11:38:23.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:46:0)
[11:38:23.404] Timer tick, asking Buildbucket for the build status
[11:38:23.480] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:23.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:47:0) after 1m0s
[11:39:23.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:47:0)
[11:39:23.484] Timer tick, asking Buildbucket for the build status
[11:39:23.792] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:23.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:48:0) after 1m0s
[11:40:23.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:48:0)
[11:40:23.810] Timer tick, asking Buildbucket for the build status
[11:40:23.892] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:23.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:49:0) after 1m0s
[11:41:23.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:49:0)
[11:41:23.913] Timer tick, asking Buildbucket for the build status
[11:41:24.013] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:24.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:50:0) after 1m0s
[11:42:24.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:50:0)
[11:42:24.068] Timer tick, asking Buildbucket for the build status
[11:42:24.229] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:24.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:51:0) after 1m0s
[11:43:24.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:51:0)
[11:43:24.290] Timer tick, asking Buildbucket for the build status
[11:43:24.491] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:24.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:52:0) after 1m0s
[11:44:24.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:52:0)
[11:44:24.521] Timer tick, asking Buildbucket for the build status
[11:44:24.615] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:24.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:53:0) after 1m0s
[11:45:24.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:53:0)
[11:45:24.646] Timer tick, asking Buildbucket for the build status
[11:45:24.781] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:24.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:54:0) after 1m0s
[11:46:24.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:54:0)
[11:46:24.773] Timer tick, asking Buildbucket for the build status
[11:46:24.985] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:24.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:55:0) after 1m0s
[11:47:25.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:55:0)
[11:47:25.004] Timer tick, asking Buildbucket for the build status
[11:47:25.110] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:25.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:56:0) after 1m0s
[11:48:25.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:56:0)
[11:48:25.130] Timer tick, asking Buildbucket for the build status
[11:48:25.274] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:25.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:57:0) after 1m0s
[11:49:25.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:57:0)
[11:49:25.333] Timer tick, asking Buildbucket for the build status
[11:49:25.614] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:25.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:58:0) after 1m0s
[11:50:25.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:58:0)
[11:50:25.633] Timer tick, asking Buildbucket for the build status
[11:50:25.711] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:25.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:59:0) after 1m0s
[11:51:25.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:59:0)
[11:51:25.727] Timer tick, asking Buildbucket for the build status
[11:51:25.906] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:25.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:60:0) after 1m0s
[11:52:25.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:60:0)
[11:52:25.925] Timer tick, asking Buildbucket for the build status
[11:52:26.031] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:26.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:61:0) after 1m0s
[11:53:26.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:61:0)
[11:53:26.059] Timer tick, asking Buildbucket for the build status
[11:53:26.195] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:26.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:62:0) after 1m0s
[11:54:26.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:62:0)
[11:54:26.215] Timer tick, asking Buildbucket for the build status
[11:54:26.396] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:26.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:63:0) after 1m0s
[11:55:26.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:63:0)
[11:55:26.428] Timer tick, asking Buildbucket for the build status
[11:55:26.657] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:26.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:64:0) after 1m0s
[11:56:26.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:64:0)
[11:56:26.691] Timer tick, asking Buildbucket for the build status
[11:56:26.767] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:26.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:65:0) after 1m0s
[11:57:26.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:65:0)
[11:57:26.742] Timer tick, asking Buildbucket for the build status
[11:57:26.809] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:26.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:66:0) after 1m0s
[11:58:26.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:66:0)
[11:58:26.827] Timer tick, asking Buildbucket for the build status
[11:58:26.873] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:26.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:67:0) after 1m0s
[11:59:26.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:67:0)
[11:59:26.894] Timer tick, asking Buildbucket for the build status
[11:59:26.959] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:26.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:68:0) after 1m0s
[12:00:26.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:68:0)
[12:00:26.970] Timer tick, asking Buildbucket for the build status
[12:00:27.155] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:27.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:69:0) after 1m0s
[12:01:27.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:69:0)
[12:01:27.178] Timer tick, asking Buildbucket for the build status
[12:01:27.248] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:27.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:70:0) after 1m0s
[12:02:27.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:70:0)
[12:02:27.272] Timer tick, asking Buildbucket for the build status
[12:02:27.335] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:27.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:71:0) after 1m0s
[12:03:27.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:71:0)
[12:03:27.355] Timer tick, asking Buildbucket for the build status
[12:03:27.609] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:27.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:72:0) after 1m0s
[12:04:27.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:72:0)
[12:04:27.646] Timer tick, asking Buildbucket for the build status
[12:04:27.967] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:27.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:73:0) after 1m0s
[12:05:27.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:73:0)
[12:05:27.988] Timer tick, asking Buildbucket for the build status
[12:05:28.301] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:28.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:74:0) after 1m0s
[12:06:28.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:74:0)
[12:06:28.276] Timer tick, asking Buildbucket for the build status
[12:06:28.341] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:28.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:75:0) after 1m0s
[12:07:28.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:75:0)
[12:07:28.362] Timer tick, asking Buildbucket for the build status
[12:07:28.466] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:28.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:76:0) after 1m0s
[12:08:28.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:76:0)
[12:08:28.497] Timer tick, asking Buildbucket for the build status
[12:08:28.546] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:28.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:77:0) after 1m0s
[12:09:28.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:77:0)
[12:09:28.580] Timer tick, asking Buildbucket for the build status
[12:09:28.884] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:28.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:78:0) after 1m0s
[12:10:28.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:78:0)
[12:10:28.892] Timer tick, asking Buildbucket for the build status
[12:10:28.962] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:28.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:79:0) after 1m0s
[12:11:28.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:79:0)
[12:11:28.984] Timer tick, asking Buildbucket for the build status
[12:11:29.061] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:29.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:80:0) after 1m0s
[12:12:29.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:80:0)
[12:12:29.089] Timer tick, asking Buildbucket for the build status
[12:12:29.373] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:29.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:81:0) after 1m0s
[12:13:29.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:81:0)
[12:13:29.394] Timer tick, asking Buildbucket for the build status
[12:13:29.458] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:29.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:82:0) after 1m0s
[12:14:29.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:82:0)
[12:14:29.478] Timer tick, asking Buildbucket for the build status
[12:14:29.555] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:29.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:83:0) after 1m0s
[12:15:29.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:83:0)
[12:15:29.577] Timer tick, asking Buildbucket for the build status
[12:15:30.039] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:30.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:84:0) after 1m0s
[12:16:30.055] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:84:0)
[12:16:30.055] Timer tick, asking Buildbucket for the build status
[12:16:30.138] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:30.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:85:0) after 1m0s
[12:17:30.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:85:0)
[12:17:30.167] Timer tick, asking Buildbucket for the build status
[12:17:30.300] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:30.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:86:0) after 1m0s
[12:18:30.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:86:0)
[12:18:30.339] Timer tick, asking Buildbucket for the build status
[12:18:30.424] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:30.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:87:0) after 1m0s
[12:19:30.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:87:0)
[12:19:30.451] Timer tick, asking Buildbucket for the build status
[12:19:30.592] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:30.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:88:0) after 1m0s
[12:20:30.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:88:0)
[12:20:30.614] Timer tick, asking Buildbucket for the build status
[12:20:30.895] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:30.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:89:0) after 1m0s
[12:21:30.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:89:0)
[12:21:30.915] Timer tick, asking Buildbucket for the build status
[12:21:31.101] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:31.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:90:0) after 1m0s
[12:22:31.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:90:0)
[12:22:31.124] Timer tick, asking Buildbucket for the build status
[12:22:31.281] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:31.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:91:0) after 1m0s
[12:23:31.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:91:0)
[12:23:31.302] Timer tick, asking Buildbucket for the build status
[12:23:31.410] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:31.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:92:0) after 1m0s
[12:24:31.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:92:0)
[12:24:31.402] Timer tick, asking Buildbucket for the build status
[12:24:31.577] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:31.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:93:0) after 1m0s
[12:25:31.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:93:0)
[12:25:31.595] Timer tick, asking Buildbucket for the build status
[12:25:31.655] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:31.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:94:0) after 1m0s
[12:26:31.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:94:0)
[12:26:31.676] Timer tick, asking Buildbucket for the build status
[12:26:32.473] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:32.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:95:0) after 1m0s
[12:27:32.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:95:0)
[12:27:32.491] Timer tick, asking Buildbucket for the build status
[12:27:32.636] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:32.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:96:0) after 1m0s
[12:28:32.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:96:0)
[12:28:32.662] Timer tick, asking Buildbucket for the build status
[12:28:32.805] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:32.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:97:0) after 1m0s
[12:29:32.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:97:0)
[12:29:32.862] Timer tick, asking Buildbucket for the build status
[12:29:32.958] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:32.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:98:0) after 1m0s
[12:30:32.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:98:0)
[12:30:32.986] Timer tick, asking Buildbucket for the build status
[12:30:33.188] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:33.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:99:0) after 1m0s
[12:31:33.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:99:0)
[12:31:33.209] Timer tick, asking Buildbucket for the build status
[12:31:33.271] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:33.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:100:0) after 1m0s
[12:32:33.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:100:0)
[12:32:33.326] Timer tick, asking Buildbucket for the build status
[12:32:33.391] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:33.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:101:0) after 1m0s
[12:33:33.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:101:0)
[12:33:33.421] Timer tick, asking Buildbucket for the build status
[12:33:33.482] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:33.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:102:0) after 1m0s
[12:34:33.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:102:0)
[12:34:33.523] Timer tick, asking Buildbucket for the build status
[12:34:33.582] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:33.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:103:0) after 1m0s
[12:35:33.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:103:0)
[12:35:33.615] Timer tick, asking Buildbucket for the build status
[12:35:33.715] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:33.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:104:0) after 1m0s
[12:36:33.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:104:0)
[12:36:33.737] Timer tick, asking Buildbucket for the build status
[12:36:33.828] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:33.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:105:0) after 1m0s
[12:37:33.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:105:0)
[12:37:33.850] Timer tick, asking Buildbucket for the build status
[12:37:34.102] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:34.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:106:0) after 1m0s
[12:38:34.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:106:0)
[12:38:34.122] Timer tick, asking Buildbucket for the build status
[12:38:34.185] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:34.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:107:0) after 1m0s
[12:39:34.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:107:0)
[12:39:34.210] Timer tick, asking Buildbucket for the build status
[12:39:34.280] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:34.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:108:0) after 1m0s
[12:40:34.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:108:0)
[12:40:34.301] Timer tick, asking Buildbucket for the build status
[12:40:34.769] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:34.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:109:0) after 1m0s
[12:41:34.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:109:0)
[12:41:34.754] Timer tick, asking Buildbucket for the build status
[12:41:34.848] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:34.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:110:0) after 1m0s
[12:42:34.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:110:0)
[12:42:34.873] Timer tick, asking Buildbucket for the build status
[12:42:34.955] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:34.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:111:0) after 1m0s
[12:43:34.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:111:0)
[12:43:34.975] Timer tick, asking Buildbucket for the build status
[12:43:35.036] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:35.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:112:0) after 1m0s
[12:44:35.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:112:0)
[12:44:35.059] Timer tick, asking Buildbucket for the build status
[12:44:35.262] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:35.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:113:0) after 1m0s
[12:45:35.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:113:0)
[12:45:35.292] Timer tick, asking Buildbucket for the build status
[12:45:35.589] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:35.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:114:0) after 1m0s
[12:46:35.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:114:0)
[12:46:35.568] Timer tick, asking Buildbucket for the build status
[12:46:35.649] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:35.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:115:0) after 1m0s
[12:47:35.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:115:0)
[12:47:35.668] Timer tick, asking Buildbucket for the build status
[12:47:35.792] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:35.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:116:0) after 1m0s
[12:48:35.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:116:0)
[12:48:35.814] Timer tick, asking Buildbucket for the build status
[12:48:35.897] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:35.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:117:0) after 1m0s
[12:49:35.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:117:0)
[12:49:35.975] Timer tick, asking Buildbucket for the build status
[12:49:36.090] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:36.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:118:0) after 1m0s
[12:50:36.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:118:0)
[12:50:36.113] Timer tick, asking Buildbucket for the build status
[12:50:36.291] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:36.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:119:0) after 1m0s
[12:51:36.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:119:0)
[12:51:36.315] Timer tick, asking Buildbucket for the build status
[12:51:36.433] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:36.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:120:0) after 1m0s
[12:52:36.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:120:0)
[12:52:36.457] Timer tick, asking Buildbucket for the build status
[12:52:36.525] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:36.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:121:0) after 1m0s
[12:53:36.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:121:0)
[12:53:36.546] Timer tick, asking Buildbucket for the build status
[12:53:36.610] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:36.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:122:0) after 1m0s
[12:54:36.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:122:0)
[12:54:36.632] Timer tick, asking Buildbucket for the build status
[12:54:36.691] Build 8917475517938848352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:36.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082905580066001744:123:0) after 1m0s
[12:54:52.573] Received PubSub notification, asking Buildbucket for the build status
[12:54:52.665] Build 8917475517938848352: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:54:52.665] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554036892087375",
"created_by": "project:fuchsia",
"created_ts": "1554029653568100",
"failure_reason": "BUILD_FAILURE",
"id": "8917475517938848352",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a2ed448bf24fe22e9a1149c6fcb583c139079b22\"}}",
"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-c-711z\", \"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\": \"a2ed448bf24fe22e9a1149c6fcb583c139079b22\", \"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-c-711z\"], \"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\": [\"4226-cfe6277\"], \"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": "1554029670157034",
"status": "COMPLETED",
"status_changed_ts": "1554029670601463",
"tags": [
"builder:clang",
"buildset:commit/git/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a2ed448bf24fe22e9a1149c6fcb583c139079b22",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082905580066001744",
"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/8917475517938848352/+/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:43ec343e31654210",
"user_agent:luci-scheduler"
],
"updated_ts": "1554036892285723",
"url": "https://ci.chromium.org/b/8917475517938848352",
"utcnow_ts": "1554036892657298"
}
}
[12:54:52.665] Invocation finished in 2h0m40.508320675s with status FAILED