[10:32:00.545] New invocation is queued and will start shortly
[10:32:01.613] Starting the invocation (attempt 1)
[10:32:01.656] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:32:01.656] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:32:01.656] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079736082554821360",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"0f89b76b84776aa98266073d36bdeb4e117c3a6d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079736082554821360",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"gitiles_ref:refs/heads/master"
]
}
[10:32:02.198] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557052321773451",
"id": "8914306020602805168",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0f89b76b84776aa98266073d36bdeb4e117c3a6d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557052322083629",
"tags": [
"builder:clang",
"buildset:commit/git/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079736082554821360",
"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/8914306020602805168/+/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": "1557052322083770",
"url": "https://ci.chromium.org/b/8914306020602805168",
"utcnow_ts": "1557052322191344"
}
}
[10:32:02.198] Task URL: https://ci.chromium.org/b/8914306020602805168
[10:32:02.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:2:0) after 1m0s
[10:32:14.934] Received PubSub notification, asking Buildbucket for the build status
[10:32:15.741] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:02.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:2:0)
[10:33:02.214] Timer tick, asking Buildbucket for the build status
[10:33:12.824] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:12.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:4:0) after 1m0s
[10:34:12.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:4:0)
[10:34:12.848] Timer tick, asking Buildbucket for the build status
[10:34:12.947] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:12.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:5:0) after 1m0s
[10:35:12.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:5:0)
[10:35:12.966] Timer tick, asking Buildbucket for the build status
[10:35:13.007] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:13.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:6:0) after 1m0s
[10:36:13.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:6:0)
[10:36:13.016] Timer tick, asking Buildbucket for the build status
[10:36:13.205] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:13.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:7:0) after 1m0s
[10:37:13.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:7:0)
[10:37:13.225] Timer tick, asking Buildbucket for the build status
[10:37:13.296] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:13.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:8:0) after 1m0s
[10:38:13.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:8:0)
[10:38:13.302] Timer tick, asking Buildbucket for the build status
[10:38:13.370] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:13.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:9:0) after 1m0s
[10:39:13.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:9:0)
[10:39:13.379] Timer tick, asking Buildbucket for the build status
[10:39:13.559] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:13.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:10:0) after 1m0s
[10:40:13.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:10:0)
[10:40:13.561] Timer tick, asking Buildbucket for the build status
[10:40:13.665] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:13.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:11:0) after 1m0s
[10:41:13.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:11:0)
[10:41:13.694] Timer tick, asking Buildbucket for the build status
[10:41:13.794] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:13.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:12:0) after 1m0s
[10:42:13.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:12:0)
[10:42:13.810] Timer tick, asking Buildbucket for the build status
[10:42:13.920] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:13.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:13:0) after 1m0s
[10:43:13.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:13:0)
[10:43:13.937] Timer tick, asking Buildbucket for the build status
[10:43:14.026] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:14.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:14:0) after 1m0s
[10:44:14.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:14:0)
[10:44:14.052] Timer tick, asking Buildbucket for the build status
[10:44:14.116] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:14.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:15:0) after 1m0s
[10:45:14.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:15:0)
[10:45:14.149] Timer tick, asking Buildbucket for the build status
[10:45:14.202] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:14.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:16:0) after 1m0s
[10:46:14.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:16:0)
[10:46:14.206] Timer tick, asking Buildbucket for the build status
[10:46:15.007] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:15.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:17:0) after 1m0s
[10:47:15.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:17:0)
[10:47:15.001] Timer tick, asking Buildbucket for the build status
[10:47:15.056] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:15.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:18:0) after 1m0s
[10:48:15.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:18:0)
[10:48:15.074] Timer tick, asking Buildbucket for the build status
[10:48:15.252] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:15.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:19:0) after 1m0s
[10:49:15.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:19:0)
[10:49:15.271] Timer tick, asking Buildbucket for the build status
[10:49:15.375] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:15.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:20:0) after 1m0s
[10:50:15.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:20:0)
[10:50:15.370] Timer tick, asking Buildbucket for the build status
[10:50:15.471] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:15.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:21:0) after 1m0s
[10:51:15.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:21:0)
[10:51:15.477] Timer tick, asking Buildbucket for the build status
[10:51:15.658] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:15.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:22:0) after 1m0s
[10:52:15.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:22:0)
[10:52:15.627] Timer tick, asking Buildbucket for the build status
[10:52:15.900] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:15.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:23:0) after 1m0s
[10:53:15.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:23:0)
[10:53:15.922] Timer tick, asking Buildbucket for the build status
[10:53:16.053] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:16.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:24:0) after 1m0s
[10:54:16.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:24:0)
[10:54:16.069] Timer tick, asking Buildbucket for the build status
[10:54:16.119] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:16.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:25:0) after 1m0s
[10:55:16.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:25:0)
[10:55:16.192] Timer tick, asking Buildbucket for the build status
[10:55:16.255] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:16.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:26:0) after 1m0s
[10:56:16.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:26:0)
[10:56:16.283] Timer tick, asking Buildbucket for the build status
[10:56:16.413] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:16.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:27:0) after 1m0s
[10:57:16.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:27:0)
[10:57:16.382] Timer tick, asking Buildbucket for the build status
[10:57:16.417] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:16.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:28:0) after 1m0s
[10:58:16.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:28:0)
[10:58:16.435] Timer tick, asking Buildbucket for the build status
[10:58:16.529] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:16.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:29:0) after 1m0s
[10:59:16.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:29:0)
[10:59:16.517] Timer tick, asking Buildbucket for the build status
[10:59:16.671] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:16.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:30:0) after 1m0s
[11:00:16.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:30:0)
[11:00:16.688] Timer tick, asking Buildbucket for the build status
[11:00:16.736] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:16.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:31:0) after 1m0s
[11:01:16.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:31:0)
[11:01:16.755] Timer tick, asking Buildbucket for the build status
[11:01:16.803] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:16.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:32:0) after 1m0s
[11:02:16.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:32:0)
[11:02:16.897] Timer tick, asking Buildbucket for the build status
[11:02:17.232] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:17.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:33:0) after 1m0s
[11:03:17.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:33:0)
[11:03:17.251] Timer tick, asking Buildbucket for the build status
[11:03:17.351] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:17.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:34:0) after 1m0s
[11:04:17.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:34:0)
[11:04:17.365] Timer tick, asking Buildbucket for the build status
[11:04:17.446] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:17.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:35:0) after 1m0s
[11:05:17.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:35:0)
[11:05:17.454] Timer tick, asking Buildbucket for the build status
[11:05:17.535] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:17.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:36:0) after 1m0s
[11:06:17.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:36:0)
[11:06:17.570] Timer tick, asking Buildbucket for the build status
[11:06:17.695] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:17.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:37:0) after 1m0s
[11:07:17.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:37:0)
[11:07:17.713] Timer tick, asking Buildbucket for the build status
[11:07:17.849] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:17.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:38:0) after 1m0s
[11:08:17.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:38:0)
[11:08:17.835] Timer tick, asking Buildbucket for the build status
[11:08:17.898] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:17.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:39:0) after 1m0s
[11:09:17.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:39:0)
[11:09:17.910] Timer tick, asking Buildbucket for the build status
[11:09:18.756] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:18.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:40:0) after 1m0s
[11:10:18.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:40:0)
[11:10:18.772] Timer tick, asking Buildbucket for the build status
[11:10:18.884] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:18.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:41:0) after 1m0s
[11:11:18.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:41:0)
[11:11:18.910] Timer tick, asking Buildbucket for the build status
[11:11:19.047] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:19.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:42:0) after 1m0s
[11:12:19.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:42:0)
[11:12:19.038] Timer tick, asking Buildbucket for the build status
[11:12:19.284] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:19.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:43:0) after 1m0s
[11:13:19.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:43:0)
[11:13:19.287] Timer tick, asking Buildbucket for the build status
[11:13:19.337] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:19.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:44:0) after 1m0s
[11:14:19.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:44:0)
[11:14:19.354] Timer tick, asking Buildbucket for the build status
[11:14:19.439] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:19.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:45:0) after 1m0s
[11:15:19.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:45:0)
[11:15:19.454] Timer tick, asking Buildbucket for the build status
[11:15:19.563] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:19.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:46:0) after 1m0s
[11:16:19.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:46:0)
[11:16:19.581] Timer tick, asking Buildbucket for the build status
[11:16:19.681] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:47:0) after 1m0s
[11:17:19.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:47:0)
[11:17:19.656] Timer tick, asking Buildbucket for the build status
[11:17:20.085] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:20.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:48:0) after 1m0s
[11:18:20.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:48:0)
[11:18:20.135] Timer tick, asking Buildbucket for the build status
[11:18:20.215] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:49:0) after 1m0s
[11:19:20.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:49:0)
[11:19:20.216] Timer tick, asking Buildbucket for the build status
[11:19:20.310] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:20.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:50:0) after 1m0s
[11:20:20.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:50:0)
[11:20:20.309] Timer tick, asking Buildbucket for the build status
[11:20:20.411] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:20.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:51:0) after 1m0s
[11:21:20.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:51:0)
[11:21:20.411] Timer tick, asking Buildbucket for the build status
[11:21:20.543] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:20.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:52:0) after 1m0s
[11:22:20.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:52:0)
[11:22:20.621] Timer tick, asking Buildbucket for the build status
[11:22:20.715] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:20.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:53:0) after 1m0s
[11:23:20.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:53:0)
[11:23:20.699] Timer tick, asking Buildbucket for the build status
[11:23:20.746] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:20.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:54:0) after 1m0s
[11:24:20.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:54:0)
[11:24:20.734] Timer tick, asking Buildbucket for the build status
[11:24:20.957] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:20.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:55:0) after 1m0s
[11:25:20.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:55:0)
[11:25:20.976] Timer tick, asking Buildbucket for the build status
[11:25:21.046] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:21.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:56:0) after 1m0s
[11:26:21.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:56:0)
[11:26:21.066] Timer tick, asking Buildbucket for the build status
[11:26:21.195] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:21.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:57:0) after 1m0s
[11:27:21.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:57:0)
[11:27:21.214] Timer tick, asking Buildbucket for the build status
[11:27:21.277] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:21.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:58:0) after 1m0s
[11:28:21.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:58:0)
[11:28:21.258] Timer tick, asking Buildbucket for the build status
[11:28:21.341] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:59:0) after 1m0s
[11:29:21.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:59:0)
[11:29:21.333] Timer tick, asking Buildbucket for the build status
[11:29:21.475] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:21.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:60:0) after 1m0s
[11:30:21.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:60:0)
[11:30:21.511] Timer tick, asking Buildbucket for the build status
[11:30:21.571] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:21.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:61:0) after 1m0s
[11:31:21.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:61:0)
[11:31:21.587] Timer tick, asking Buildbucket for the build status
[11:31:21.677] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:21.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:62:0) after 1m0s
[11:32:21.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:62:0)
[11:32:21.720] Timer tick, asking Buildbucket for the build status
[11:32:21.963] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:21.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:63:0) after 1m0s
[11:33:21.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:63:0)
[11:33:21.973] Timer tick, asking Buildbucket for the build status
[11:33:22.044] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:22.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:64:0) after 1m0s
[11:34:22.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:64:0)
[11:34:22.064] Timer tick, asking Buildbucket for the build status
[11:34:22.292] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:22.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:65:0) after 1m0s
[11:35:22.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:65:0)
[11:35:22.314] Timer tick, asking Buildbucket for the build status
[11:35:22.477] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:22.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:66:0) after 1m0s
[11:36:22.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:66:0)
[11:36:22.497] Timer tick, asking Buildbucket for the build status
[11:36:22.635] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:22.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:67:0) after 1m0s
[11:37:22.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:67:0)
[11:37:22.656] Timer tick, asking Buildbucket for the build status
[11:37:22.716] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:22.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:68:0) after 1m0s
[11:38:22.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:68:0)
[11:38:22.717] Timer tick, asking Buildbucket for the build status
[11:38:22.788] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:22.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:69:0) after 1m0s
[11:39:22.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:69:0)
[11:39:22.801] Timer tick, asking Buildbucket for the build status
[11:39:22.866] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:22.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:70:0) after 1m0s
[11:40:22.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:70:0)
[11:40:22.857] Timer tick, asking Buildbucket for the build status
[11:40:23.151] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:23.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:71:0) after 1m0s
[11:41:23.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:71:0)
[11:41:23.169] Timer tick, asking Buildbucket for the build status
[11:41:23.258] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:23.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:72:0) after 1m0s
[11:42:23.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:72:0)
[11:42:23.263] Timer tick, asking Buildbucket for the build status
[11:42:23.308] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:23.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:73:0) after 1m0s
[11:43:23.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:73:0)
[11:43:23.328] Timer tick, asking Buildbucket for the build status
[11:43:23.502] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:23.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:74:0) after 1m0s
[11:44:23.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:74:0)
[11:44:23.523] Timer tick, asking Buildbucket for the build status
[11:44:23.573] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:23.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:75:0) after 1m0s
[11:45:23.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:75:0)
[11:45:23.561] Timer tick, asking Buildbucket for the build status
[11:45:23.694] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:23.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:76:0) after 1m0s
[11:46:23.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:76:0)
[11:46:23.717] Timer tick, asking Buildbucket for the build status
[11:46:23.816] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:23.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:77:0) after 1m0s
[11:47:23.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:77:0)
[11:47:23.835] Timer tick, asking Buildbucket for the build status
[11:47:23.929] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:23.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:78:0) after 1m0s
[11:48:23.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:78:0)
[11:48:23.949] Timer tick, asking Buildbucket for the build status
[11:48:24.085] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:24.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:79:0) after 1m0s
[11:49:24.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:79:0)
[11:49:24.166] Timer tick, asking Buildbucket for the build status
[11:49:24.206] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:24.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:80:0) after 1m0s
[11:50:24.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:80:0)
[11:50:24.210] Timer tick, asking Buildbucket for the build status
[11:50:24.379] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:24.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:81:0) after 1m0s
[11:51:24.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:81:0)
[11:51:24.472] Timer tick, asking Buildbucket for the build status
[11:51:24.817] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:24.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:82:0) after 1m0s
[11:52:24.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:82:0)
[11:52:24.838] Timer tick, asking Buildbucket for the build status
[11:52:24.919] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:24.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:83:0) after 1m0s
[11:53:24.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:83:0)
[11:53:24.938] Timer tick, asking Buildbucket for the build status
[11:53:25.181] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:25.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:84:0) after 1m0s
[11:54:25.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:84:0)
[11:54:25.241] Timer tick, asking Buildbucket for the build status
[11:54:25.304] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:25.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:85:0) after 1m0s
[11:55:25.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:85:0)
[11:55:25.328] Timer tick, asking Buildbucket for the build status
[11:55:25.495] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:25.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:86:0) after 1m0s
[11:56:25.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:86:0)
[11:56:25.514] Timer tick, asking Buildbucket for the build status
[11:56:25.621] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:25.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:87:0) after 1m0s
[11:57:25.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:87:0)
[11:57:25.632] Timer tick, asking Buildbucket for the build status
[11:57:25.717] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:25.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:88:0) after 1m0s
[11:58:25.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:88:0)
[11:58:25.736] Timer tick, asking Buildbucket for the build status
[11:58:25.901] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:25.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:89:0) after 1m0s
[11:59:25.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:89:0)
[11:59:25.921] Timer tick, asking Buildbucket for the build status
[11:59:26.025] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:26.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:90:0) after 1m0s
[12:00:26.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:90:0)
[12:00:26.115] Timer tick, asking Buildbucket for the build status
[12:00:26.447] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:26.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:91:0) after 1m0s
[12:01:26.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:91:0)
[12:01:26.471] Timer tick, asking Buildbucket for the build status
[12:01:26.592] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:26.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:92:0) after 1m0s
[12:02:26.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:92:0)
[12:02:26.613] Timer tick, asking Buildbucket for the build status
[12:02:26.906] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:26.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:93:0) after 1m0s
[12:03:26.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:93:0)
[12:03:26.929] Timer tick, asking Buildbucket for the build status
[12:03:27.022] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:27.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:94:0) after 1m0s
[12:04:27.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:94:0)
[12:04:27.091] Timer tick, asking Buildbucket for the build status
[12:04:27.149] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:27.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:95:0) after 1m0s
[12:05:27.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:95:0)
[12:05:27.265] Timer tick, asking Buildbucket for the build status
[12:05:27.585] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:27.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:96:0) after 1m0s
[12:06:27.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:96:0)
[12:06:27.605] Timer tick, asking Buildbucket for the build status
[12:06:28.136] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:28.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:97:0) after 1m0s
[12:07:28.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:97:0)
[12:07:28.154] Timer tick, asking Buildbucket for the build status
[12:07:28.289] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:28.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:98:0) after 1m0s
[12:08:28.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:98:0)
[12:08:28.308] Timer tick, asking Buildbucket for the build status
[12:08:28.367] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:28.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:99:0) after 1m0s
[12:09:28.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:99:0)
[12:09:28.396] Timer tick, asking Buildbucket for the build status
[12:09:28.698] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:28.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:100:0) after 1m0s
[12:10:28.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:100:0)
[12:10:28.718] Timer tick, asking Buildbucket for the build status
[12:10:28.760] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:28.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:101:0) after 1m0s
[12:11:28.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:101:0)
[12:11:28.792] Timer tick, asking Buildbucket for the build status
[12:11:28.940] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:28.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:102:0) after 1m0s
[12:12:28.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:102:0)
[12:12:28.944] Timer tick, asking Buildbucket for the build status
[12:12:29.109] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:29.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:103:0) after 1m0s
[12:13:29.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:103:0)
[12:13:29.128] Timer tick, asking Buildbucket for the build status
[12:13:29.219] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:29.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:104:0) after 1m0s
[12:14:29.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:104:0)
[12:14:29.196] Timer tick, asking Buildbucket for the build status
[12:14:29.356] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:29.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:105:0) after 1m0s
[12:15:29.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:105:0)
[12:15:29.367] Timer tick, asking Buildbucket for the build status
[12:15:29.405] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:29.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:106:0) after 1m0s
[12:16:29.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:106:0)
[12:16:29.442] Timer tick, asking Buildbucket for the build status
[12:16:29.547] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:29.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:107:0) after 1m0s
[12:17:29.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:107:0)
[12:17:29.568] Timer tick, asking Buildbucket for the build status
[12:17:29.726] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:29.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:108:0) after 1m0s
[12:18:29.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:108:0)
[12:18:29.747] Timer tick, asking Buildbucket for the build status
[12:18:29.789] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:29.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:109:0) after 1m0s
[12:19:29.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:109:0)
[12:19:29.828] Timer tick, asking Buildbucket for the build status
[12:19:29.877] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:29.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:110:0) after 1m0s
[12:20:29.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:110:0)
[12:20:29.901] Timer tick, asking Buildbucket for the build status
[12:20:29.975] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:29.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:111:0) after 1m0s
[12:21:30.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:111:0)
[12:21:30.077] Timer tick, asking Buildbucket for the build status
[12:21:30.137] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:30.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:112:0) after 1m0s
[12:22:30.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:112:0)
[12:22:30.206] Timer tick, asking Buildbucket for the build status
[12:22:30.257] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:30.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:113:0) after 1m0s
[12:23:30.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:113:0)
[12:23:30.292] Timer tick, asking Buildbucket for the build status
[12:23:30.376] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:30.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:114:0) after 1m0s
[12:24:30.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:114:0)
[12:24:30.398] Timer tick, asking Buildbucket for the build status
[12:24:30.489] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:30.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:115:0) after 1m0s
[12:25:30.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:115:0)
[12:25:30.512] Timer tick, asking Buildbucket for the build status
[12:25:30.589] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:30.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:116:0) after 1m0s
[12:26:30.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:116:0)
[12:26:30.588] Timer tick, asking Buildbucket for the build status
[12:26:30.792] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:30.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:117:0) after 1m0s
[12:27:30.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:117:0)
[12:27:30.827] Timer tick, asking Buildbucket for the build status
[12:27:31.189] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:31.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:118:0) after 1m0s
[12:28:31.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:118:0)
[12:28:31.241] Timer tick, asking Buildbucket for the build status
[12:28:31.380] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:31.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:119:0) after 1m0s
[12:29:31.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:119:0)
[12:29:31.401] Timer tick, asking Buildbucket for the build status
[12:29:31.485] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:31.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:120:0) after 1m0s
[12:30:31.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:120:0)
[12:30:31.506] Timer tick, asking Buildbucket for the build status
[12:30:31.796] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:31.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:121:0) after 1m0s
[12:31:31.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:121:0)
[12:31:31.822] Timer tick, asking Buildbucket for the build status
[12:31:31.994] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:31.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:122:0) after 1m0s
[12:32:32.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:122:0)
[12:32:32.019] Timer tick, asking Buildbucket for the build status
[12:32:32.115] Build 8914306020602805168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:32.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079736082554821360:123:0) after 1m0s
[12:32:48.290] Received PubSub notification, asking Buildbucket for the build status
[12:32:48.345] Build 8914306020602805168: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:32:48.345] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557059567936610",
"created_by": "project:fuchsia",
"created_ts": "1557052321773451",
"failure_reason": "BUILD_FAILURE",
"id": "8914306020602805168",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"0f89b76b84776aa98266073d36bdeb4e117c3a6d\"}}",
"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-9rzq\", \"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\": \"0f89b76b84776aa98266073d36bdeb4e117c3a6d\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-003729e9-us-central1-c-9rzq\"], \"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\": [\"4284-fd5e65b\"], \"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": "1557052333380516",
"status": "COMPLETED",
"status_changed_ts": "1557052334469994",
"tags": [
"builder:clang",
"buildset:commit/git/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/0f89b76b84776aa98266073d36bdeb4e117c3a6d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079736082554821360",
"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/8914306020602805168/+/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:44a05e7c6d456a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557059568074598",
"url": "https://ci.chromium.org/b/8914306020602805168",
"utcnow_ts": "1557059568329647"
}
}
[12:32:48.345] Invocation finished in 2h0m47.816834838s with status FAILED