[14:41:08.671] New invocation is queued and will start shortly
[14:41:09.788] Starting the invocation (attempt 1)
[14:41:09.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:41:09.828] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:41:09.828] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083253690007940208",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b5630a2ab10692d035664eb0fb0209b9c788b83c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083253690007940208",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"gitiles_ref:refs/heads/master"
]
}
[14:41:10.458] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553697669971530",
"id": "8917823627970907808",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b5630a2ab10692d035664eb0fb0209b9c788b83c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553697670280739",
"tags": [
"builder:clang",
"buildset:commit/git/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083253690007940208",
"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/8917823627970907808/+/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": "1553697670280864",
"url": "https://ci.chromium.org/b/8917823627970907808",
"utcnow_ts": "1553697670448051"
}
}
[14:41:10.458] Task URL: https://ci.chromium.org/b/8917823627970907808
[14:41:10.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:2:0) after 1m0s
[14:41:26.420] Received PubSub notification, asking Buildbucket for the build status
[14:41:26.520] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:10.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:2:0)
[14:42:10.472] Timer tick, asking Buildbucket for the build status
[14:42:11.124] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:11.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:4:0) after 1m0s
[14:43:11.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:4:0)
[14:43:11.123] Timer tick, asking Buildbucket for the build status
[14:43:11.278] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:11.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:5:0) after 1m0s
[14:44:11.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:5:0)
[14:44:11.297] Timer tick, asking Buildbucket for the build status
[14:44:11.347] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:11.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:6:0) after 1m0s
[14:45:11.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:6:0)
[14:45:11.338] Timer tick, asking Buildbucket for the build status
[14:45:11.776] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:11.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:7:0) after 1m0s
[14:46:11.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:7:0)
[14:46:11.782] Timer tick, asking Buildbucket for the build status
[14:46:11.821] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:11.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:8:0) after 1m0s
[14:47:11.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:8:0)
[14:47:11.839] Timer tick, asking Buildbucket for the build status
[14:47:11.886] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:11.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:9:0) after 1m0s
[14:48:11.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:9:0)
[14:48:11.906] Timer tick, asking Buildbucket for the build status
[14:48:12.004] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:12.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:10:0) after 1m0s
[14:49:11.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:10:0)
[14:49:11.997] Timer tick, asking Buildbucket for the build status
[14:49:12.153] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:12.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:11:0) after 1m0s
[14:50:12.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:11:0)
[14:50:12.171] Timer tick, asking Buildbucket for the build status
[14:50:12.387] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:12.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:12:0) after 1m0s
[14:51:12.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:12:0)
[14:51:12.375] Timer tick, asking Buildbucket for the build status
[14:51:12.647] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:12.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:13:0) after 1m0s
[14:52:12.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:13:0)
[14:52:12.715] Timer tick, asking Buildbucket for the build status
[14:52:12.780] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:12.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:14:0) after 1m0s
[14:53:12.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:14:0)
[14:53:12.798] Timer tick, asking Buildbucket for the build status
[14:53:12.865] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:12.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:15:0) after 1m0s
[14:54:12.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:15:0)
[14:54:12.933] Timer tick, asking Buildbucket for the build status
[14:54:12.984] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:12.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:16:0) after 1m0s
[14:55:13.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:16:0)
[14:55:13.026] Timer tick, asking Buildbucket for the build status
[14:55:13.528] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:13.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:17:0) after 1m0s
[14:56:13.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:17:0)
[14:56:13.513] Timer tick, asking Buildbucket for the build status
[14:56:13.558] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:13.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:18:0) after 1m0s
[14:57:13.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:18:0)
[14:57:13.586] Timer tick, asking Buildbucket for the build status
[14:57:13.761] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:13.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:19:0) after 1m0s
[14:58:13.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:19:0)
[14:58:13.773] Timer tick, asking Buildbucket for the build status
[14:58:13.825] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:13.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:20:0) after 1m0s
[14:59:13.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:20:0)
[14:59:13.828] Timer tick, asking Buildbucket for the build status
[14:59:13.894] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:13.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:21:0) after 1m0s
[15:00:13.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:21:0)
[15:00:13.913] Timer tick, asking Buildbucket for the build status
[15:00:13.962] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:13.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:22:0) after 1m0s
[15:01:13.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:22:0)
[15:01:13.981] Timer tick, asking Buildbucket for the build status
[15:01:14.044] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:14.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:23:0) after 1m0s
[15:02:14.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:23:0)
[15:02:14.065] Timer tick, asking Buildbucket for the build status
[15:02:14.180] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:14.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:24:0) after 1m0s
[15:03:14.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:24:0)
[15:03:14.198] Timer tick, asking Buildbucket for the build status
[15:03:14.264] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:14.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:25:0) after 1m0s
[15:04:14.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:25:0)
[15:04:14.293] Timer tick, asking Buildbucket for the build status
[15:04:14.345] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:14.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:26:0) after 1m0s
[15:05:14.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:26:0)
[15:05:14.361] Timer tick, asking Buildbucket for the build status
[15:05:14.407] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:14.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:27:0) after 1m0s
[15:06:14.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:27:0)
[15:06:14.432] Timer tick, asking Buildbucket for the build status
[15:06:14.497] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:14.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:28:0) after 1m0s
[15:07:14.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:28:0)
[15:07:14.519] Timer tick, asking Buildbucket for the build status
[15:07:14.569] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:14.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:29:0) after 1m0s
[15:08:14.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:29:0)
[15:08:14.617] Timer tick, asking Buildbucket for the build status
[15:08:14.736] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:14.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:30:0) after 1m0s
[15:09:14.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:30:0)
[15:09:14.772] Timer tick, asking Buildbucket for the build status
[15:09:14.822] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:14.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:31:0) after 1m0s
[15:10:14.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:31:0)
[15:10:14.846] Timer tick, asking Buildbucket for the build status
[15:10:15.069] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:15.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:32:0) after 1m0s
[15:11:15.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:32:0)
[15:11:15.105] Timer tick, asking Buildbucket for the build status
[15:11:16.008] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:16.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:33:0) after 1m0s
[15:12:16.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:33:0)
[15:12:16.033] Timer tick, asking Buildbucket for the build status
[15:12:16.102] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:16.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:34:0) after 1m0s
[15:13:16.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:34:0)
[15:13:16.134] Timer tick, asking Buildbucket for the build status
[15:13:16.357] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:16.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:35:0) after 1m0s
[15:14:16.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:35:0)
[15:14:16.402] Timer tick, asking Buildbucket for the build status
[15:14:16.459] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:16.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:36:0) after 1m0s
[15:15:16.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:36:0)
[15:15:16.487] Timer tick, asking Buildbucket for the build status
[15:15:16.533] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:16.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:37:0) after 1m0s
[15:16:16.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:37:0)
[15:16:16.556] Timer tick, asking Buildbucket for the build status
[15:16:16.697] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:16.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:38:0) after 1m0s
[15:17:16.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:38:0)
[15:17:16.742] Timer tick, asking Buildbucket for the build status
[15:17:16.871] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:16.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:39:0) after 1m0s
[15:18:16.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:39:0)
[15:18:16.892] Timer tick, asking Buildbucket for the build status
[15:18:16.955] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:16.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:40:0) after 1m0s
[15:19:16.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:40:0)
[15:19:16.975] Timer tick, asking Buildbucket for the build status
[15:19:17.024] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:17.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:41:0) after 1m0s
[15:20:17.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:41:0)
[15:20:17.047] Timer tick, asking Buildbucket for the build status
[15:20:17.167] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:17.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:42:0) after 1m0s
[15:21:17.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:42:0)
[15:21:17.142] Timer tick, asking Buildbucket for the build status
[15:21:17.262] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:17.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:43:0) after 1m0s
[15:22:17.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:43:0)
[15:22:17.343] Timer tick, asking Buildbucket for the build status
[15:22:17.395] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:17.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:44:0) after 1m0s
[15:23:17.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:44:0)
[15:23:17.417] Timer tick, asking Buildbucket for the build status
[15:23:17.528] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:17.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:45:0) after 1m0s
[15:24:17.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:45:0)
[15:24:17.553] Timer tick, asking Buildbucket for the build status
[15:24:17.608] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:17.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:46:0) after 1m0s
[15:25:17.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:46:0)
[15:25:17.637] Timer tick, asking Buildbucket for the build status
[15:25:17.702] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:17.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:47:0) after 1m0s
[15:26:17.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:47:0)
[15:26:17.739] Timer tick, asking Buildbucket for the build status
[15:26:17.809] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:17.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:48:0) after 1m0s
[15:27:17.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:48:0)
[15:27:17.811] Timer tick, asking Buildbucket for the build status
[15:27:17.866] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:17.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:49:0) after 1m0s
[15:28:17.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:49:0)
[15:28:17.870] Timer tick, asking Buildbucket for the build status
[15:28:17.935] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:50:0) after 1m0s
[15:29:17.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:50:0)
[15:29:17.995] Timer tick, asking Buildbucket for the build status
[15:29:18.059] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:18.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:51:0) after 1m0s
[15:30:18.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:51:0)
[15:30:18.079] Timer tick, asking Buildbucket for the build status
[15:30:18.122] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:18.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:52:0) after 1m0s
[15:31:18.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:52:0)
[15:31:18.150] Timer tick, asking Buildbucket for the build status
[15:31:18.333] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:18.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:53:0) after 1m0s
[15:32:18.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:53:0)
[15:32:18.329] Timer tick, asking Buildbucket for the build status
[15:32:18.480] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:18.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:54:0) after 1m0s
[15:33:18.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:54:0)
[15:33:18.517] Timer tick, asking Buildbucket for the build status
[15:33:18.574] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:18.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:55:0) after 1m0s
[15:34:18.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:55:0)
[15:34:18.595] Timer tick, asking Buildbucket for the build status
[15:34:18.642] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:18.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:56:0) after 1m0s
[15:35:18.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:56:0)
[15:35:18.627] Timer tick, asking Buildbucket for the build status
[15:35:18.816] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:18.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:57:0) after 1m0s
[15:36:18.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:57:0)
[15:36:18.803] Timer tick, asking Buildbucket for the build status
[15:36:18.841] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:18.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:58:0) after 1m0s
[15:37:18.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:58:0)
[15:37:18.865] Timer tick, asking Buildbucket for the build status
[15:37:18.918] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:18.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:59:0) after 1m0s
[15:38:18.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:59:0)
[15:38:18.911] Timer tick, asking Buildbucket for the build status
[15:38:19.048] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:19.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:60:0) after 1m0s
[15:39:19.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:60:0)
[15:39:19.060] Timer tick, asking Buildbucket for the build status
[15:39:19.140] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:19.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:61:0) after 1m0s
[15:40:19.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:61:0)
[15:40:19.159] Timer tick, asking Buildbucket for the build status
[15:40:19.311] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:19.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:62:0) after 1m0s
[15:41:19.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:62:0)
[15:41:19.321] Timer tick, asking Buildbucket for the build status
[15:41:19.500] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:19.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:63:0) after 1m0s
[15:42:19.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:63:0)
[15:42:19.591] Timer tick, asking Buildbucket for the build status
[15:42:19.770] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:19.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:64:0) after 1m0s
[15:43:19.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:64:0)
[15:43:19.812] Timer tick, asking Buildbucket for the build status
[15:43:19.862] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:19.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:65:0) after 1m0s
[15:44:19.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:65:0)
[15:44:19.920] Timer tick, asking Buildbucket for the build status
[15:44:19.987] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:19.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:66:0) after 1m0s
[15:45:19.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:66:0)
[15:45:19.983] Timer tick, asking Buildbucket for the build status
[15:45:20.091] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:20.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:67:0) after 1m0s
[15:46:20.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:67:0)
[15:46:20.076] Timer tick, asking Buildbucket for the build status
[15:46:20.156] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:20.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:68:0) after 1m0s
[15:47:20.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:68:0)
[15:47:20.167] Timer tick, asking Buildbucket for the build status
[15:47:20.222] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:20.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:69:0) after 1m0s
[15:48:20.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:69:0)
[15:48:20.222] Timer tick, asking Buildbucket for the build status
[15:48:20.309] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:20.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:70:0) after 1m0s
[15:49:20.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:70:0)
[15:49:20.330] Timer tick, asking Buildbucket for the build status
[15:49:20.407] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:20.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:71:0) after 1m0s
[15:50:20.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:71:0)
[15:50:20.437] Timer tick, asking Buildbucket for the build status
[15:50:20.491] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:20.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:72:0) after 1m0s
[15:51:20.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:72:0)
[15:51:20.475] Timer tick, asking Buildbucket for the build status
[15:51:20.789] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:20.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:73:0) after 1m0s
[15:52:20.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:73:0)
[15:52:20.818] Timer tick, asking Buildbucket for the build status
[15:52:20.948] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:20.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:74:0) after 1m0s
[15:53:20.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:74:0)
[15:53:20.935] Timer tick, asking Buildbucket for the build status
[15:53:20.998] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:20.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:75:0) after 1m0s
[15:54:21.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:75:0)
[15:54:21.023] Timer tick, asking Buildbucket for the build status
[15:54:21.068] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:21.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:76:0) after 1m0s
[15:55:21.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:76:0)
[15:55:21.095] Timer tick, asking Buildbucket for the build status
[15:55:21.207] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:21.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:77:0) after 1m0s
[15:56:21.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:77:0)
[15:56:21.209] Timer tick, asking Buildbucket for the build status
[15:56:21.307] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:21.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:78:0) after 1m0s
[15:57:21.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:78:0)
[15:57:21.336] Timer tick, asking Buildbucket for the build status
[15:57:21.574] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:21.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:79:0) after 1m0s
[15:58:21.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:79:0)
[15:58:21.580] Timer tick, asking Buildbucket for the build status
[15:58:21.641] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:21.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:80:0) after 1m0s
[15:59:21.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:80:0)
[15:59:21.664] Timer tick, asking Buildbucket for the build status
[15:59:21.967] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:21.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:81:0) after 1m0s
[16:00:21.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:81:0)
[16:00:21.994] Timer tick, asking Buildbucket for the build status
[16:00:22.085] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:22.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:82:0) after 1m0s
[16:01:22.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:82:0)
[16:01:22.105] Timer tick, asking Buildbucket for the build status
[16:01:22.175] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:22.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:83:0) after 1m0s
[16:02:22.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:83:0)
[16:02:22.208] Timer tick, asking Buildbucket for the build status
[16:02:22.272] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:22.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:84:0) after 1m0s
[16:03:22.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:84:0)
[16:03:22.297] Timer tick, asking Buildbucket for the build status
[16:03:22.347] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:22.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:85:0) after 1m0s
[16:04:22.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:85:0)
[16:04:22.379] Timer tick, asking Buildbucket for the build status
[16:04:22.515] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:22.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:86:0) after 1m0s
[16:05:22.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:86:0)
[16:05:22.533] Timer tick, asking Buildbucket for the build status
[16:05:22.585] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:22.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:87:0) after 1m0s
[16:06:22.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:87:0)
[16:06:22.611] Timer tick, asking Buildbucket for the build status
[16:06:22.726] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:22.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:88:0) after 1m0s
[16:07:22.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:88:0)
[16:07:22.763] Timer tick, asking Buildbucket for the build status
[16:07:22.837] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:22.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:89:0) after 1m0s
[16:08:22.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:89:0)
[16:08:22.866] Timer tick, asking Buildbucket for the build status
[16:08:22.953] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:22.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:90:0) after 1m0s
[16:09:22.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:90:0)
[16:09:22.942] Timer tick, asking Buildbucket for the build status
[16:09:23.128] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:23.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:91:0) after 1m0s
[16:10:23.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:91:0)
[16:10:23.165] Timer tick, asking Buildbucket for the build status
[16:10:23.217] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:92:0) after 1m0s
[16:11:23.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:92:0)
[16:11:23.210] Timer tick, asking Buildbucket for the build status
[16:11:23.324] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:23.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:93:0) after 1m0s
[16:12:23.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:93:0)
[16:12:23.373] Timer tick, asking Buildbucket for the build status
[16:12:23.463] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:23.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:94:0) after 1m0s
[16:13:23.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:94:0)
[16:13:23.447] Timer tick, asking Buildbucket for the build status
[16:13:23.502] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:23.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:95:0) after 1m0s
[16:14:23.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:95:0)
[16:14:23.538] Timer tick, asking Buildbucket for the build status
[16:14:23.598] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:23.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:96:0) after 1m0s
[16:15:23.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:96:0)
[16:15:23.621] Timer tick, asking Buildbucket for the build status
[16:15:23.731] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:23.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:97:0) after 1m0s
[16:16:23.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:97:0)
[16:16:23.724] Timer tick, asking Buildbucket for the build status
[16:16:23.772] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:23.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:98:0) after 1m0s
[16:17:23.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:98:0)
[16:17:23.806] Timer tick, asking Buildbucket for the build status
[16:17:23.854] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:23.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:99:0) after 1m0s
[16:18:23.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:99:0)
[16:18:23.849] Timer tick, asking Buildbucket for the build status
[16:18:24.136] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:24.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:100:0) after 1m0s
[16:19:24.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:100:0)
[16:19:24.110] Timer tick, asking Buildbucket for the build status
[16:19:24.372] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:24.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:101:0) after 1m0s
[16:20:24.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:101:0)
[16:20:24.415] Timer tick, asking Buildbucket for the build status
[16:20:24.544] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:24.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:102:0) after 1m0s
[16:21:24.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:102:0)
[16:21:24.583] Timer tick, asking Buildbucket for the build status
[16:21:24.678] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:24.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:103:0) after 1m0s
[16:22:24.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:103:0)
[16:22:24.678] Timer tick, asking Buildbucket for the build status
[16:22:24.743] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:24.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:104:0) after 1m0s
[16:23:24.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:104:0)
[16:23:24.728] Timer tick, asking Buildbucket for the build status
[16:23:24.776] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:24.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:105:0) after 1m0s
[16:24:24.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:105:0)
[16:24:24.748] Timer tick, asking Buildbucket for the build status
[16:24:24.802] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:24.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:106:0) after 1m0s
[16:25:24.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:106:0)
[16:25:24.852] Timer tick, asking Buildbucket for the build status
[16:25:24.897] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:24.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:107:0) after 1m0s
[16:26:24.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:107:0)
[16:26:24.920] Timer tick, asking Buildbucket for the build status
[16:26:25.092] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:25.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:108:0) after 1m0s
[16:27:25.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:108:0)
[16:27:25.094] Timer tick, asking Buildbucket for the build status
[16:27:25.140] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:25.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:109:0) after 1m0s
[16:28:25.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:109:0)
[16:28:25.145] Timer tick, asking Buildbucket for the build status
[16:28:25.195] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:25.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:110:0) after 1m0s
[16:29:25.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:110:0)
[16:29:25.223] Timer tick, asking Buildbucket for the build status
[16:29:25.281] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:25.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:111:0) after 1m0s
[16:30:25.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:111:0)
[16:30:25.294] Timer tick, asking Buildbucket for the build status
[16:30:25.335] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:25.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:112:0) after 1m0s
[16:31:25.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:112:0)
[16:31:25.363] Timer tick, asking Buildbucket for the build status
[16:31:25.431] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:25.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:113:0) after 1m0s
[16:32:25.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:113:0)
[16:32:25.467] Timer tick, asking Buildbucket for the build status
[16:32:25.541] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:25.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:114:0) after 1m0s
[16:33:25.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:114:0)
[16:33:25.578] Timer tick, asking Buildbucket for the build status
[16:33:25.632] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:25.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:115:0) after 1m0s
[16:34:25.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:115:0)
[16:34:25.625] Timer tick, asking Buildbucket for the build status
[16:34:25.693] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:25.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:116:0) after 1m0s
[16:35:25.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:116:0)
[16:35:25.686] Timer tick, asking Buildbucket for the build status
[16:35:25.743] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:25.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:117:0) after 1m0s
[16:36:25.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:117:0)
[16:36:25.776] Timer tick, asking Buildbucket for the build status
[16:36:25.839] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:25.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:118:0) after 1m0s
[16:37:25.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:118:0)
[16:37:25.935] Timer tick, asking Buildbucket for the build status
[16:37:26.086] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:26.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:119:0) after 1m0s
[16:38:26.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:119:0)
[16:38:26.087] Timer tick, asking Buildbucket for the build status
[16:38:26.311] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:26.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:120:0) after 1m0s
[16:39:26.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:120:0)
[16:39:26.299] Timer tick, asking Buildbucket for the build status
[16:39:26.432] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:26.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:121:0) after 1m0s
[16:40:26.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:121:0)
[16:40:26.507] Timer tick, asking Buildbucket for the build status
[16:40:26.681] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:26.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:122:0) after 1m0s
[16:41:26.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:122:0)
[16:41:26.679] Timer tick, asking Buildbucket for the build status
[16:41:26.801] Build 8917823627970907808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:26.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083253690007940208:123:0) after 1m0s
[16:41:55.610] Received PubSub notification, asking Buildbucket for the build status
[16:41:55.654] Build 8917823627970907808: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:41:55.654] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553704915182133",
"created_by": "project:fuchsia",
"created_ts": "1553697669971530",
"failure_reason": "BUILD_FAILURE",
"id": "8917823627970907808",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b5630a2ab10692d035664eb0fb0209b9c788b83c\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-b-tpbp\", \"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\": \"b5630a2ab10692d035664eb0fb0209b9c788b83c\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-b-tpbp\"], \"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\": [\"4221-9c50278\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1553697685771597",
"status": "COMPLETED",
"status_changed_ts": "1553697686208568",
"tags": [
"builder:clang",
"buildset:commit/git/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b5630a2ab10692d035664eb0fb0209b9c788b83c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083253690007940208",
"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/8917823627970907808/+/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:43d86a90363f8e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553704915297868",
"url": "https://ci.chromium.org/b/8917823627970907808",
"utcnow_ts": "1553704915641495"
}
}
[16:41:55.654] Invocation finished in 2h0m47.008602992s with status FAILED