[20:48:20.892] New invocation is queued and will start shortly
[20:48:21.969] Starting the invocation (attempt 1)
[20:48:22.045] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:48:22.045] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:48:22.045] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079425514950867280",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"73b7272e7a87be08e4f5775dbd163685d57558a1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079425514950867280",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/73b7272e7a87be08e4f5775dbd163685d57558a1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/73b7272e7a87be08e4f5775dbd163685d57558a1",
"gitiles_ref:refs/heads/master"
]
}
[20:48:23.071] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557348502213332",
"id": "8913995452901911520",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"73b7272e7a87be08e4f5775dbd163685d57558a1\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557348502758807",
"tags": [
"builder:clang",
"buildset:commit/git/73b7272e7a87be08e4f5775dbd163685d57558a1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/73b7272e7a87be08e4f5775dbd163685d57558a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079425514950867280",
"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/8913995452901911520/+/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": "1557348502759040",
"url": "https://ci.chromium.org/b/8913995452901911520",
"utcnow_ts": "1557348503057037"
}
}
[20:48:23.071] Task URL: https://ci.chromium.org/b/8913995452901911520
[20:48:23.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:2:0) after 1m0s
[20:48:35.823] Received PubSub notification, asking Buildbucket for the build status
[20:48:35.982] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:23.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:2:0)
[20:49:23.090] Timer tick, asking Buildbucket for the build status
[20:49:23.247] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:23.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:4:0) after 1m0s
[20:50:23.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:4:0)
[20:50:23.267] Timer tick, asking Buildbucket for the build status
[20:50:23.338] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:23.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:5:0) after 1m0s
[20:51:23.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:5:0)
[20:51:23.328] Timer tick, asking Buildbucket for the build status
[20:51:23.395] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:23.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:6:0) after 1m0s
[20:52:23.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:6:0)
[20:52:23.400] Timer tick, asking Buildbucket for the build status
[20:52:23.947] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:23.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:7:0) after 1m0s
[20:53:23.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:7:0)
[20:53:23.922] Timer tick, asking Buildbucket for the build status
[20:53:24.071] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:24.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:8:0) after 1m0s
[20:54:24.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:8:0)
[20:54:24.087] Timer tick, asking Buildbucket for the build status
[20:54:24.224] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:24.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:9:0) after 1m0s
[20:55:24.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:9:0)
[20:55:24.205] Timer tick, asking Buildbucket for the build status
[20:55:24.304] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:24.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:10:0) after 1m0s
[20:56:24.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:10:0)
[20:56:24.412] Timer tick, asking Buildbucket for the build status
[20:56:24.648] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:24.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:11:0) after 1m0s
[20:57:24.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:11:0)
[20:57:24.712] Timer tick, asking Buildbucket for the build status
[20:57:24.852] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:24.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:12:0) after 1m0s
[20:58:24.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:12:0)
[20:58:24.877] Timer tick, asking Buildbucket for the build status
[20:58:25.087] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:25.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:13:0) after 1m0s
[20:59:25.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:13:0)
[20:59:25.083] Timer tick, asking Buildbucket for the build status
[20:59:25.282] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:25.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:14:0) after 1m0s
[21:00:25.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:14:0)
[21:00:25.282] Timer tick, asking Buildbucket for the build status
[21:00:25.476] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:25.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:15:0) after 1m0s
[21:01:25.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:15:0)
[21:01:25.493] Timer tick, asking Buildbucket for the build status
[21:01:25.536] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:25.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:16:0) after 1m0s
[21:02:25.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:16:0)
[21:02:25.684] Timer tick, asking Buildbucket for the build status
[21:02:25.723] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:25.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:17:0) after 1m0s
[21:03:25.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:17:0)
[21:03:25.924] Timer tick, asking Buildbucket for the build status
[21:03:25.990] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:25.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:18:0) after 1m0s
[21:04:26.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:18:0)
[21:04:26.228] Timer tick, asking Buildbucket for the build status
[21:04:26.330] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:26.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:19:0) after 1m0s
[21:05:26.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:19:0)
[21:05:26.800] Timer tick, asking Buildbucket for the build status
[21:05:26.846] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:26.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:20:0) after 1m0s
[21:06:26.868] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:20:0)
[21:06:26.868] Timer tick, asking Buildbucket for the build status
[21:06:26.934] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:26.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:21:0) after 1m0s
[21:07:26.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:21:0)
[21:07:26.926] Timer tick, asking Buildbucket for the build status
[21:07:26.992] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:26.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:22:0) after 1m0s
[21:08:27.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:22:0)
[21:08:27.021] Timer tick, asking Buildbucket for the build status
[21:08:27.176] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:27.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:23:0) after 1m0s
[21:09:27.219] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:23:0)
[21:09:27.219] Timer tick, asking Buildbucket for the build status
[21:09:27.297] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:27.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:24:0) after 1m0s
[21:10:27.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:24:0)
[21:10:27.373] Timer tick, asking Buildbucket for the build status
[21:10:27.437] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:27.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:25:0) after 1m0s
[21:11:27.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:25:0)
[21:11:27.466] Timer tick, asking Buildbucket for the build status
[21:11:27.537] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:27.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:26:0) after 1m0s
[21:12:27.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:26:0)
[21:12:27.637] Timer tick, asking Buildbucket for the build status
[21:12:27.710] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:27.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:27:0) after 1m0s
[21:13:27.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:27:0)
[21:13:27.732] Timer tick, asking Buildbucket for the build status
[21:13:27.772] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:27.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:28:0) after 1m0s
[21:14:27.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:28:0)
[21:14:27.843] Timer tick, asking Buildbucket for the build status
[21:14:28.017] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:28.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:29:0) after 1m0s
[21:15:28.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:29:0)
[21:15:28.044] Timer tick, asking Buildbucket for the build status
[21:15:28.326] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:28.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:30:0) after 1m0s
[21:16:28.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:30:0)
[21:16:28.352] Timer tick, asking Buildbucket for the build status
[21:16:28.414] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:28.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:31:0) after 1m0s
[21:17:28.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:31:0)
[21:17:28.440] Timer tick, asking Buildbucket for the build status
[21:17:28.496] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:28.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:32:0) after 1m0s
[21:18:29.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:32:0)
[21:18:29.927] Timer tick, asking Buildbucket for the build status
[21:18:29.985] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:29.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:33:0) after 1m0s
[21:19:30.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:33:0)
[21:19:30.027] Timer tick, asking Buildbucket for the build status
[21:19:30.210] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:30.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:34:0) after 1m0s
[21:20:30.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:34:0)
[21:20:30.197] Timer tick, asking Buildbucket for the build status
[21:20:30.361] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:30.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:35:0) after 1m0s
[21:21:30.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:35:0)
[21:21:30.377] Timer tick, asking Buildbucket for the build status
[21:21:30.465] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:30.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:36:0) after 1m0s
[21:22:30.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:36:0)
[21:22:30.478] Timer tick, asking Buildbucket for the build status
[21:22:30.798] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:30.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:37:0) after 1m0s
[21:23:30.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:37:0)
[21:23:30.802] Timer tick, asking Buildbucket for the build status
[21:23:31.102] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:31.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:38:0) after 1m0s
[21:24:31.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:38:0)
[21:24:31.190] Timer tick, asking Buildbucket for the build status
[21:24:31.249] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:31.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:39:0) after 1m0s
[21:25:31.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:39:0)
[21:25:31.274] Timer tick, asking Buildbucket for the build status
[21:25:31.393] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:31.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:40:0) after 1m0s
[21:26:31.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:40:0)
[21:26:31.384] Timer tick, asking Buildbucket for the build status
[21:26:31.473] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:31.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:41:0) after 1m0s
[21:27:31.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:41:0)
[21:27:31.459] Timer tick, asking Buildbucket for the build status
[21:27:31.686] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:31.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:42:0) after 1m0s
[21:28:31.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:42:0)
[21:28:31.692] Timer tick, asking Buildbucket for the build status
[21:28:32.106] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:32.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:43:0) after 1m0s
[21:29:32.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:43:0)
[21:29:32.137] Timer tick, asking Buildbucket for the build status
[21:29:32.190] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:32.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:44:0) after 1m0s
[21:30:32.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:44:0)
[21:30:32.212] Timer tick, asking Buildbucket for the build status
[21:30:32.254] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:32.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:45:0) after 1m0s
[21:31:32.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:45:0)
[21:31:32.300] Timer tick, asking Buildbucket for the build status
[21:31:32.361] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:32.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:46:0) after 1m0s
[21:32:32.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:46:0)
[21:32:32.374] Timer tick, asking Buildbucket for the build status
[21:32:32.423] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:32.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:47:0) after 1m0s
[21:33:32.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:47:0)
[21:33:32.412] Timer tick, asking Buildbucket for the build status
[21:33:32.593] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:32.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:48:0) after 1m0s
[21:34:32.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:48:0)
[21:34:32.579] Timer tick, asking Buildbucket for the build status
[21:34:32.643] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:32.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:49:0) after 1m0s
[21:35:32.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:49:0)
[21:35:32.704] Timer tick, asking Buildbucket for the build status
[21:35:32.793] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:32.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:50:0) after 1m0s
[21:36:32.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:50:0)
[21:36:32.883] Timer tick, asking Buildbucket for the build status
[21:36:33.018] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:33.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:51:0) after 1m0s
[21:37:33.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:51:0)
[21:37:33.064] Timer tick, asking Buildbucket for the build status
[21:37:33.283] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:33.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:52:0) after 1m0s
[21:38:33.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:52:0)
[21:38:33.329] Timer tick, asking Buildbucket for the build status
[21:38:33.371] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:33.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:53:0) after 1m0s
[21:39:33.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:53:0)
[21:39:33.373] Timer tick, asking Buildbucket for the build status
[21:39:33.464] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:33.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:54:0) after 1m0s
[21:40:33.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:54:0)
[21:40:33.457] Timer tick, asking Buildbucket for the build status
[21:40:33.505] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:33.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:55:0) after 1m0s
[21:41:33.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:55:0)
[21:41:33.902] Timer tick, asking Buildbucket for the build status
[21:41:34.442] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:34.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:56:0) after 1m0s
[21:42:34.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:56:0)
[21:42:34.425] Timer tick, asking Buildbucket for the build status
[21:42:34.585] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:34.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:57:0) after 1m0s
[21:43:34.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:57:0)
[21:43:34.602] Timer tick, asking Buildbucket for the build status
[21:43:34.783] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:34.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:58:0) after 1m0s
[21:44:34.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:58:0)
[21:44:34.779] Timer tick, asking Buildbucket for the build status
[21:44:34.836] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:34.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:59:0) after 1m0s
[21:45:34.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:59:0)
[21:45:34.836] Timer tick, asking Buildbucket for the build status
[21:45:35.487] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:35.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:60:0) after 1m0s
[21:46:35.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:60:0)
[21:46:35.520] Timer tick, asking Buildbucket for the build status
[21:46:35.820] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:35.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:61:0) after 1m0s
[21:47:35.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:61:0)
[21:47:35.807] Timer tick, asking Buildbucket for the build status
[21:47:35.940] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:35.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:62:0) after 1m0s
[21:48:35.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:62:0)
[21:48:35.914] Timer tick, asking Buildbucket for the build status
[21:48:35.960] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:35.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:63:0) after 1m0s
[21:49:35.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:63:0)
[21:49:35.981] Timer tick, asking Buildbucket for the build status
[21:49:36.055] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:36.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:64:0) after 1m0s
[21:50:36.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:64:0)
[21:50:36.081] Timer tick, asking Buildbucket for the build status
[21:50:36.140] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:36.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:65:0) after 1m0s
[21:51:36.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:65:0)
[21:51:36.180] Timer tick, asking Buildbucket for the build status
[21:51:36.269] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:36.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:66:0) after 1m0s
[21:52:36.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:66:0)
[21:52:36.291] Timer tick, asking Buildbucket for the build status
[21:52:36.332] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:36.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:67:0) after 1m0s
[21:53:36.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:67:0)
[21:53:36.355] Timer tick, asking Buildbucket for the build status
[21:53:36.534] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:36.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:68:0) after 1m0s
[21:54:36.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:68:0)
[21:54:36.560] Timer tick, asking Buildbucket for the build status
[21:54:36.616] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:36.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:69:0) after 1m0s
[21:55:36.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:69:0)
[21:55:36.595] Timer tick, asking Buildbucket for the build status
[21:55:36.689] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:36.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:70:0) after 1m0s
[21:56:36.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:70:0)
[21:56:36.737] Timer tick, asking Buildbucket for the build status
[21:56:36.796] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:36.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:71:0) after 1m0s
[21:57:36.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:71:0)
[21:57:36.828] Timer tick, asking Buildbucket for the build status
[21:57:36.977] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:36.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:72:0) after 1m0s
[21:58:36.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:72:0)
[21:58:36.968] Timer tick, asking Buildbucket for the build status
[21:58:37.136] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:37.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:73:0) after 1m0s
[21:59:37.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:73:0)
[21:59:37.185] Timer tick, asking Buildbucket for the build status
[21:59:37.279] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:37.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:74:0) after 1m0s
[22:00:37.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:74:0)
[22:00:37.270] Timer tick, asking Buildbucket for the build status
[22:00:37.385] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:37.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:75:0) after 1m0s
[22:01:37.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:75:0)
[22:01:37.488] Timer tick, asking Buildbucket for the build status
[22:01:37.556] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:37.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:76:0) after 1m0s
[22:02:37.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:76:0)
[22:02:37.588] Timer tick, asking Buildbucket for the build status
[22:02:37.653] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:37.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:77:0) after 1m0s
[22:03:37.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:77:0)
[22:03:37.679] Timer tick, asking Buildbucket for the build status
[22:03:37.806] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:37.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:78:0) after 1m0s
[22:04:37.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:78:0)
[22:04:37.795] Timer tick, asking Buildbucket for the build status
[22:04:37.835] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:37.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:79:0) after 1m0s
[22:05:37.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:79:0)
[22:05:37.854] Timer tick, asking Buildbucket for the build status
[22:05:38.015] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:38.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:80:0) after 1m0s
[22:06:38.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:80:0)
[22:06:38.046] Timer tick, asking Buildbucket for the build status
[22:06:38.085] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:38.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:81:0) after 1m0s
[22:07:38.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:81:0)
[22:07:38.127] Timer tick, asking Buildbucket for the build status
[22:07:38.185] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:38.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:82:0) after 1m0s
[22:08:38.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:82:0)
[22:08:38.212] Timer tick, asking Buildbucket for the build status
[22:08:38.263] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:38.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:83:0) after 1m0s
[22:09:38.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:83:0)
[22:09:38.286] Timer tick, asking Buildbucket for the build status
[22:09:38.425] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:38.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:84:0) after 1m0s
[22:10:38.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:84:0)
[22:10:38.416] Timer tick, asking Buildbucket for the build status
[22:10:38.515] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:38.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:85:0) after 1m0s
[22:11:38.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:85:0)
[22:11:38.517] Timer tick, asking Buildbucket for the build status
[22:11:40.425] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:40.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:86:0) after 1m0s
[22:12:40.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:86:0)
[22:12:40.404] Timer tick, asking Buildbucket for the build status
[22:12:40.466] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:40.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:87:0) after 1m0s
[22:13:40.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:87:0)
[22:13:40.460] Timer tick, asking Buildbucket for the build status
[22:13:40.748] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:40.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:88:0) after 1m0s
[22:14:40.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:88:0)
[22:14:40.756] Timer tick, asking Buildbucket for the build status
[22:14:40.799] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:40.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:89:0) after 1m0s
[22:15:40.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:89:0)
[22:15:40.817] Timer tick, asking Buildbucket for the build status
[22:15:41.099] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:41.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:90:0) after 1m0s
[22:16:41.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:90:0)
[22:16:41.131] Timer tick, asking Buildbucket for the build status
[22:16:41.216] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:41.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:91:0) after 1m0s
[22:17:41.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:91:0)
[22:17:41.192] Timer tick, asking Buildbucket for the build status
[22:17:41.275] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:41.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:92:0) after 1m0s
[22:18:41.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:92:0)
[22:18:41.296] Timer tick, asking Buildbucket for the build status
[22:18:41.414] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:41.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:93:0) after 1m0s
[22:19:41.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:93:0)
[22:19:41.453] Timer tick, asking Buildbucket for the build status
[22:19:41.514] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:41.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:94:0) after 1m0s
[22:20:41.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:94:0)
[22:20:41.503] Timer tick, asking Buildbucket for the build status
[22:20:41.561] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:41.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:95:0) after 1m0s
[22:21:41.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:95:0)
[22:21:41.556] Timer tick, asking Buildbucket for the build status
[22:21:41.639] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:41.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:96:0) after 1m0s
[22:22:41.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:96:0)
[22:22:41.640] Timer tick, asking Buildbucket for the build status
[22:22:41.801] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:41.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:97:0) after 1m0s
[22:23:41.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:97:0)
[22:23:41.862] Timer tick, asking Buildbucket for the build status
[22:23:41.964] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:41.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:98:0) after 1m0s
[22:24:41.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:98:0)
[22:24:41.992] Timer tick, asking Buildbucket for the build status
[22:24:42.094] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:42.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:99:0) after 1m0s
[22:25:42.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:99:0)
[22:25:42.069] Timer tick, asking Buildbucket for the build status
[22:25:42.150] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:42.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:100:0) after 1m0s
[22:26:42.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:100:0)
[22:26:42.148] Timer tick, asking Buildbucket for the build status
[22:26:42.203] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:42.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:101:0) after 1m0s
[22:27:42.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:101:0)
[22:27:42.222] Timer tick, asking Buildbucket for the build status
[22:27:42.273] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:42.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:102:0) after 1m0s
[22:28:42.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:102:0)
[22:28:42.298] Timer tick, asking Buildbucket for the build status
[22:28:42.354] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:42.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:103:0) after 1m0s
[22:29:42.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:103:0)
[22:29:42.373] Timer tick, asking Buildbucket for the build status
[22:29:42.425] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:42.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:104:0) after 1m0s
[22:30:42.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:104:0)
[22:30:42.465] Timer tick, asking Buildbucket for the build status
[22:30:42.563] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:42.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:105:0) after 1m0s
[22:31:42.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:105:0)
[22:31:42.666] Timer tick, asking Buildbucket for the build status
[22:31:42.717] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:42.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:106:0) after 1m0s
[22:32:42.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:106:0)
[22:32:42.791] Timer tick, asking Buildbucket for the build status
[22:32:42.849] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:107:0) after 1m0s
[22:33:42.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:107:0)
[22:33:42.869] Timer tick, asking Buildbucket for the build status
[22:33:43.147] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:43.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:108:0) after 1m0s
[22:34:43.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:108:0)
[22:34:43.173] Timer tick, asking Buildbucket for the build status
[22:34:43.217] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:109:0) after 1m0s
[22:35:43.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:109:0)
[22:35:43.210] Timer tick, asking Buildbucket for the build status
[22:35:43.283] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:43.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:110:0) after 1m0s
[22:36:43.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:110:0)
[22:36:43.275] Timer tick, asking Buildbucket for the build status
[22:36:43.331] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:43.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:111:0) after 1m0s
[22:37:43.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:111:0)
[22:37:43.373] Timer tick, asking Buildbucket for the build status
[22:37:43.421] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:43.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:112:0) after 1m0s
[22:38:43.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:112:0)
[22:38:43.432] Timer tick, asking Buildbucket for the build status
[22:38:43.806] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:43.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:113:0) after 1m0s
[22:39:43.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:113:0)
[22:39:43.808] Timer tick, asking Buildbucket for the build status
[22:39:45.480] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:45.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:114:0) after 1m0s
[22:40:45.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:114:0)
[22:40:45.592] Timer tick, asking Buildbucket for the build status
[22:40:45.672] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:45.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:115:0) after 1m0s
[22:41:45.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:115:0)
[22:41:45.845] Timer tick, asking Buildbucket for the build status
[22:41:46.165] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:46.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:116:0) after 1m0s
[22:42:46.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:116:0)
[22:42:46.150] Timer tick, asking Buildbucket for the build status
[22:42:46.572] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:46.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:117:0) after 1m0s
[22:43:46.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:117:0)
[22:43:46.603] Timer tick, asking Buildbucket for the build status
[22:43:46.650] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:46.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:118:0) after 1m0s
[22:44:46.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:118:0)
[22:44:46.673] Timer tick, asking Buildbucket for the build status
[22:44:46.785] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:46.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:119:0) after 1m0s
[22:45:46.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:119:0)
[22:45:46.808] Timer tick, asking Buildbucket for the build status
[22:45:46.951] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:46.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:120:0) after 1m0s
[22:46:46.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:120:0)
[22:46:46.975] Timer tick, asking Buildbucket for the build status
[22:46:47.306] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:47.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:121:0) after 1m0s
[22:47:48.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:121:0)
[22:47:48.343] Timer tick, asking Buildbucket for the build status
[22:47:48.406] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:48.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:122:0) after 1m0s
[22:48:48.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:122:0)
[22:48:48.458] Timer tick, asking Buildbucket for the build status
[22:48:48.534] Build 8913995452901911520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:48.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079425514950867280:123:0) after 1m0s
[22:49:21.559] Received PubSub notification, asking Buildbucket for the build status
[22:49:21.705] Build 8913995452901911520: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:49:21.705] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557355753150806",
"created_by": "project:fuchsia",
"created_ts": "1557348502213332",
"failure_reason": "BUILD_FAILURE",
"id": "8913995452901911520",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"73b7272e7a87be08e4f5775dbd163685d57558a1\"}}",
"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-prod-1-us-central1-b-0-kecz\", \"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\": \"73b7272e7a87be08e4f5775dbd163685d57558a1\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-b-0-kecz\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4291-fed3552\"], \"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": "1557348514850639",
"status": "COMPLETED",
"status_changed_ts": "1557348515320269",
"tags": [
"builder:clang",
"buildset:commit/git/73b7272e7a87be08e4f5775dbd163685d57558a1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/73b7272e7a87be08e4f5775dbd163685d57558a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079425514950867280",
"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/8913995452901911520/+/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:44b205d9112fe610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557355753222711",
"url": "https://ci.chromium.org/b/8913995452901911520",
"utcnow_ts": "1557355761697120"
}
}
[22:49:21.706] Invocation finished in 2h1m0.830162535s with status FAILED