[12:23:56.184] New invocation is queued and will start shortly
[12:23:57.245] Starting the invocation (attempt 1)
[12:23:57.292] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:23:57.292] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:23:57.292] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081631576989654416",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b1b580e047ad87914585747c908263ae3131d48e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081631576989654416",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b1b580e047ad87914585747c908263ae3131d48e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b1b580e047ad87914585747c908263ae3131d48e",
"gitiles_ref:refs/heads/master"
]
}
[12:23:58.502] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555244637452002",
"id": "8916201514997100480",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b1b580e047ad87914585747c908263ae3131d48e\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555244638251927",
"tags": [
"builder:clang",
"buildset:commit/git/b1b580e047ad87914585747c908263ae3131d48e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b1b580e047ad87914585747c908263ae3131d48e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081631576989654416",
"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/8916201514997100480/+/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": "1555244638252066",
"url": "https://ci.chromium.org/b/8916201514997100480",
"utcnow_ts": "1555244638491075"
}
}
[12:23:58.502] Task URL: https://ci.chromium.org/b/8916201514997100480
[12:23:58.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:2:0) after 1m0s
[12:23:59.593] Received PubSub notification, asking Buildbucket for the build status
[12:23:59.637] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:58.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:2:0)
[12:24:58.484] Timer tick, asking Buildbucket for the build status
[12:24:58.952] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:58.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:4:0) after 1m0s
[12:25:58.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:4:0)
[12:25:58.967] Timer tick, asking Buildbucket for the build status
[12:25:59.173] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:59.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:5:0) after 1m0s
[12:26:59.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:5:0)
[12:26:59.189] Timer tick, asking Buildbucket for the build status
[12:26:59.350] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:59.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:6:0) after 1m0s
[12:27:59.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:6:0)
[12:27:59.369] Timer tick, asking Buildbucket for the build status
[12:27:59.422] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:59.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:7:0) after 1m0s
[12:28:59.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:7:0)
[12:28:59.439] Timer tick, asking Buildbucket for the build status
[12:28:59.506] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:59.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:8:0) after 1m0s
[12:29:59.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:8:0)
[12:29:59.595] Timer tick, asking Buildbucket for the build status
[12:29:59.653] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:59.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:9:0) after 1m0s
[12:30:59.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:9:0)
[12:30:59.673] Timer tick, asking Buildbucket for the build status
[12:30:59.786] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:59.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:10:0) after 1m0s
[12:31:59.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:10:0)
[12:31:59.765] Timer tick, asking Buildbucket for the build status
[12:31:59.944] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:59.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:11:0) after 1m0s
[12:32:59.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:11:0)
[12:32:59.949] Timer tick, asking Buildbucket for the build status
[12:33:00.031] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:00.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:12:0) after 1m0s
[12:34:00.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:12:0)
[12:34:00.016] Timer tick, asking Buildbucket for the build status
[12:34:00.088] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:00.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:13:0) after 1m0s
[12:35:00.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:13:0)
[12:35:00.136] Timer tick, asking Buildbucket for the build status
[12:35:00.340] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:00.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:14:0) after 1m0s
[12:36:00.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:14:0)
[12:36:00.364] Timer tick, asking Buildbucket for the build status
[12:36:00.516] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:00.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:15:0) after 1m0s
[12:37:00.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:15:0)
[12:37:00.537] Timer tick, asking Buildbucket for the build status
[12:37:00.598] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:00.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:16:0) after 1m0s
[12:38:00.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:16:0)
[12:38:00.577] Timer tick, asking Buildbucket for the build status
[12:38:00.686] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:00.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:17:0) after 1m0s
[12:39:00.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:17:0)
[12:39:00.692] Timer tick, asking Buildbucket for the build status
[12:39:00.762] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:00.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:18:0) after 1m0s
[12:40:00.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:18:0)
[12:40:00.786] Timer tick, asking Buildbucket for the build status
[12:40:00.942] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:00.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:19:0) after 1m0s
[12:41:00.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:19:0)
[12:41:00.965] Timer tick, asking Buildbucket for the build status
[12:41:01.189] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:01.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:20:0) after 1m0s
[12:42:01.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:20:0)
[12:42:01.183] Timer tick, asking Buildbucket for the build status
[12:42:01.266] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:01.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:21:0) after 1m0s
[12:43:01.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:21:0)
[12:43:01.283] Timer tick, asking Buildbucket for the build status
[12:43:01.468] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:01.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:22:0) after 1m0s
[12:44:01.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:22:0)
[12:44:01.486] Timer tick, asking Buildbucket for the build status
[12:44:01.721] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:01.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:23:0) after 1m0s
[12:45:01.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:23:0)
[12:45:01.739] Timer tick, asking Buildbucket for the build status
[12:45:03.964] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:03.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:24:0) after 1m0s
[12:46:03.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:24:0)
[12:46:03.984] Timer tick, asking Buildbucket for the build status
[12:46:04.119] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:04.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:25:0) after 1m0s
[12:47:04.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:25:0)
[12:47:04.144] Timer tick, asking Buildbucket for the build status
[12:47:04.289] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:04.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:26:0) after 1m0s
[12:48:04.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:26:0)
[12:48:04.312] Timer tick, asking Buildbucket for the build status
[12:48:04.484] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:04.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:27:0) after 1m0s
[12:49:04.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:27:0)
[12:49:04.506] Timer tick, asking Buildbucket for the build status
[12:49:04.573] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:04.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:28:0) after 1m0s
[12:50:04.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:28:0)
[12:50:04.589] Timer tick, asking Buildbucket for the build status
[12:50:05.137] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:05.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:29:0) after 1m0s
[12:51:05.162] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:29:0)
[12:51:05.162] Timer tick, asking Buildbucket for the build status
[12:51:05.228] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:05.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:30:0) after 1m0s
[12:52:05.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:30:0)
[12:52:05.247] Timer tick, asking Buildbucket for the build status
[12:52:05.450] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:05.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:31:0) after 1m0s
[12:53:05.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:31:0)
[12:53:05.480] Timer tick, asking Buildbucket for the build status
[12:53:05.562] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:05.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:32:0) after 1m0s
[12:54:05.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:32:0)
[12:54:05.579] Timer tick, asking Buildbucket for the build status
[12:54:05.658] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:05.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:33:0) after 1m0s
[12:55:05.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:33:0)
[12:55:05.677] Timer tick, asking Buildbucket for the build status
[12:55:05.748] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:05.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:34:0) after 1m0s
[12:56:05.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:34:0)
[12:56:05.758] Timer tick, asking Buildbucket for the build status
[12:56:05.875] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:05.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:35:0) after 1m0s
[12:57:05.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:35:0)
[12:57:05.930] Timer tick, asking Buildbucket for the build status
[12:57:06.026] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:06.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:36:0) after 1m0s
[12:58:06.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:36:0)
[12:58:06.064] Timer tick, asking Buildbucket for the build status
[12:58:06.153] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:06.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:37:0) after 1m0s
[12:59:06.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:37:0)
[12:59:06.182] Timer tick, asking Buildbucket for the build status
[12:59:06.382] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:06.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:38:0) after 1m0s
[13:00:06.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:38:0)
[13:00:06.401] Timer tick, asking Buildbucket for the build status
[13:00:08.270] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:08.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:39:0) after 1m0s
[13:01:08.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:39:0)
[13:01:08.287] Timer tick, asking Buildbucket for the build status
[13:01:08.381] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:08.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:40:0) after 1m0s
[13:02:08.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:40:0)
[13:02:08.397] Timer tick, asking Buildbucket for the build status
[13:02:08.456] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:08.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:41:0) after 1m0s
[13:03:08.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:41:0)
[13:03:08.473] Timer tick, asking Buildbucket for the build status
[13:03:08.628] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:08.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:42:0) after 1m0s
[13:04:08.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:42:0)
[13:04:08.649] Timer tick, asking Buildbucket for the build status
[13:04:08.979] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:08.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:43:0) after 1m0s
[13:05:08.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:43:0)
[13:05:08.955] Timer tick, asking Buildbucket for the build status
[13:05:09.021] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:09.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:44:0) after 1m0s
[13:06:09.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:44:0)
[13:06:09.049] Timer tick, asking Buildbucket for the build status
[13:06:09.112] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:09.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:45:0) after 1m0s
[13:07:09.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:45:0)
[13:07:09.197] Timer tick, asking Buildbucket for the build status
[13:07:09.481] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:09.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:46:0) after 1m0s
[13:08:09.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:46:0)
[13:08:09.500] Timer tick, asking Buildbucket for the build status
[13:08:09.568] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:09.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:47:0) after 1m0s
[13:09:09.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:47:0)
[13:09:09.589] Timer tick, asking Buildbucket for the build status
[13:09:10.130] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:10.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:48:0) after 1m0s
[13:10:10.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:48:0)
[13:10:10.153] Timer tick, asking Buildbucket for the build status
[13:10:10.444] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:10.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:49:0) after 1m0s
[13:11:10.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:49:0)
[13:11:10.461] Timer tick, asking Buildbucket for the build status
[13:11:10.882] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:10.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:50:0) after 1m0s
[13:12:10.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:50:0)
[13:12:10.909] Timer tick, asking Buildbucket for the build status
[13:12:10.982] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:10.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:51:0) after 1m0s
[13:13:11.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:51:0)
[13:13:11.001] Timer tick, asking Buildbucket for the build status
[13:13:11.069] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:11.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:52:0) after 1m0s
[13:14:11.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:52:0)
[13:14:11.091] Timer tick, asking Buildbucket for the build status
[13:14:11.196] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:11.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:53:0) after 1m0s
[13:15:11.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:53:0)
[13:15:11.189] Timer tick, asking Buildbucket for the build status
[13:15:11.327] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:11.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:54:0) after 1m0s
[13:16:11.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:54:0)
[13:16:11.347] Timer tick, asking Buildbucket for the build status
[13:16:11.427] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:11.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:55:0) after 1m0s
[13:17:11.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:55:0)
[13:17:11.431] Timer tick, asking Buildbucket for the build status
[13:17:11.553] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:11.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:56:0) after 1m0s
[13:18:11.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:56:0)
[13:18:11.577] Timer tick, asking Buildbucket for the build status
[13:18:11.706] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:11.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:57:0) after 1m0s
[13:19:11.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:57:0)
[13:19:11.693] Timer tick, asking Buildbucket for the build status
[13:19:11.814] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:11.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:58:0) after 1m0s
[13:20:11.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:58:0)
[13:20:11.782] Timer tick, asking Buildbucket for the build status
[13:20:11.974] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:11.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:59:0) after 1m0s
[13:21:11.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:59:0)
[13:21:11.995] Timer tick, asking Buildbucket for the build status
[13:21:12.067] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:12.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:60:0) after 1m0s
[13:22:12.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:60:0)
[13:22:12.083] Timer tick, asking Buildbucket for the build status
[13:22:12.262] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:12.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:61:0) after 1m0s
[13:23:12.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:61:0)
[13:23:12.311] Timer tick, asking Buildbucket for the build status
[13:23:12.414] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:12.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:62:0) after 1m0s
[13:24:12.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:62:0)
[13:24:12.439] Timer tick, asking Buildbucket for the build status
[13:24:12.661] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:12.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:63:0) after 1m0s
[13:25:12.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:63:0)
[13:25:12.682] Timer tick, asking Buildbucket for the build status
[13:25:12.746] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:12.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:64:0) after 1m0s
[13:26:12.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:64:0)
[13:26:12.738] Timer tick, asking Buildbucket for the build status
[13:26:12.870] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:12.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:65:0) after 1m0s
[13:27:12.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:65:0)
[13:27:12.876] Timer tick, asking Buildbucket for the build status
[13:27:12.994] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:12.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:66:0) after 1m0s
[13:28:13.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:66:0)
[13:28:13.096] Timer tick, asking Buildbucket for the build status
[13:28:13.228] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:13.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:67:0) after 1m0s
[13:29:13.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:67:0)
[13:29:13.248] Timer tick, asking Buildbucket for the build status
[13:29:13.302] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:13.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:68:0) after 1m0s
[13:30:13.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:68:0)
[13:30:13.337] Timer tick, asking Buildbucket for the build status
[13:30:13.401] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:13.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:69:0) after 1m0s
[13:31:13.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:69:0)
[13:31:13.409] Timer tick, asking Buildbucket for the build status
[13:31:13.481] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:70:0) after 1m0s
[13:32:13.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:70:0)
[13:32:13.499] Timer tick, asking Buildbucket for the build status
[13:32:13.594] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:71:0) after 1m0s
[13:33:13.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:71:0)
[13:33:13.600] Timer tick, asking Buildbucket for the build status
[13:33:13.669] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:13.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:72:0) after 1m0s
[13:34:13.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:72:0)
[13:34:13.691] Timer tick, asking Buildbucket for the build status
[13:34:13.860] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:13.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:73:0) after 1m0s
[13:35:13.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:73:0)
[13:35:13.880] Timer tick, asking Buildbucket for the build status
[13:35:13.963] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:13.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:74:0) after 1m0s
[13:36:13.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:74:0)
[13:36:13.985] Timer tick, asking Buildbucket for the build status
[13:36:14.209] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:14.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:75:0) after 1m0s
[13:37:14.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:75:0)
[13:37:14.198] Timer tick, asking Buildbucket for the build status
[13:37:14.341] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:14.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:76:0) after 1m0s
[13:38:14.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:76:0)
[13:38:14.361] Timer tick, asking Buildbucket for the build status
[13:38:14.434] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:14.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:77:0) after 1m0s
[13:39:14.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:77:0)
[13:39:14.470] Timer tick, asking Buildbucket for the build status
[13:39:14.558] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:14.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:78:0) after 1m0s
[13:40:14.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:78:0)
[13:40:14.604] Timer tick, asking Buildbucket for the build status
[13:40:14.709] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:14.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:79:0) after 1m0s
[13:41:14.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:79:0)
[13:41:14.728] Timer tick, asking Buildbucket for the build status
[13:41:14.872] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:14.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:80:0) after 1m0s
[13:42:14.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:80:0)
[13:42:14.926] Timer tick, asking Buildbucket for the build status
[13:42:15.111] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:81:0) after 1m0s
[13:43:15.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:81:0)
[13:43:15.133] Timer tick, asking Buildbucket for the build status
[13:43:15.227] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:15.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:82:0) after 1m0s
[13:44:15.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:82:0)
[13:44:15.267] Timer tick, asking Buildbucket for the build status
[13:44:15.391] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:15.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:83:0) after 1m0s
[13:45:15.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:83:0)
[13:45:15.411] Timer tick, asking Buildbucket for the build status
[13:45:15.484] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:15.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:84:0) after 1m0s
[13:46:15.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:84:0)
[13:46:15.469] Timer tick, asking Buildbucket for the build status
[13:46:15.535] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:15.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:85:0) after 1m0s
[13:47:15.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:85:0)
[13:47:15.564] Timer tick, asking Buildbucket for the build status
[13:47:15.634] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:15.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:86:0) after 1m0s
[13:48:15.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:86:0)
[13:48:15.664] Timer tick, asking Buildbucket for the build status
[13:48:15.794] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:15.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:87:0) after 1m0s
[13:49:15.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:87:0)
[13:49:15.819] Timer tick, asking Buildbucket for the build status
[13:49:15.895] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:15.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:88:0) after 1m0s
[13:50:15.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:88:0)
[13:50:15.923] Timer tick, asking Buildbucket for the build status
[13:50:16.118] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:16.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:89:0) after 1m0s
[13:51:16.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:89:0)
[13:51:16.117] Timer tick, asking Buildbucket for the build status
[13:51:16.360] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:16.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:90:0) after 1m0s
[13:52:16.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:90:0)
[13:52:16.382] Timer tick, asking Buildbucket for the build status
[13:52:16.669] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:16.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:91:0) after 1m0s
[13:53:16.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:91:0)
[13:53:16.692] Timer tick, asking Buildbucket for the build status
[13:53:16.746] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:16.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:92:0) after 1m0s
[13:54:16.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:92:0)
[13:54:16.781] Timer tick, asking Buildbucket for the build status
[13:54:16.845] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:16.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:93:0) after 1m0s
[13:55:16.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:93:0)
[13:55:16.867] Timer tick, asking Buildbucket for the build status
[13:55:16.937] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:16.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:94:0) after 1m0s
[13:56:16.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:94:0)
[13:56:16.910] Timer tick, asking Buildbucket for the build status
[13:56:16.975] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:16.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:95:0) after 1m0s
[13:57:16.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:95:0)
[13:57:16.996] Timer tick, asking Buildbucket for the build status
[13:57:17.084] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:17.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:96:0) after 1m0s
[13:58:17.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:96:0)
[13:58:17.108] Timer tick, asking Buildbucket for the build status
[13:58:17.164] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:17.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:97:0) after 1m0s
[13:59:17.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:97:0)
[13:59:17.186] Timer tick, asking Buildbucket for the build status
[13:59:17.228] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:17.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:98:0) after 1m0s
[14:00:17.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:98:0)
[14:00:17.248] Timer tick, asking Buildbucket for the build status
[14:00:17.325] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:17.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:99:0) after 1m0s
[14:01:17.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:99:0)
[14:01:17.343] Timer tick, asking Buildbucket for the build status
[14:01:17.464] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:17.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:100:0) after 1m0s
[14:02:17.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:100:0)
[14:02:17.484] Timer tick, asking Buildbucket for the build status
[14:02:17.551] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:17.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:101:0) after 1m0s
[14:03:17.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:101:0)
[14:03:17.571] Timer tick, asking Buildbucket for the build status
[14:03:17.746] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:17.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:102:0) after 1m0s
[14:04:17.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:102:0)
[14:04:17.765] Timer tick, asking Buildbucket for the build status
[14:04:17.829] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:17.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:103:0) after 1m0s
[14:05:17.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:103:0)
[14:05:17.849] Timer tick, asking Buildbucket for the build status
[14:05:17.910] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:17.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:104:0) after 1m0s
[14:06:17.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:104:0)
[14:06:17.932] Timer tick, asking Buildbucket for the build status
[14:06:17.996] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:17.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:105:0) after 1m0s
[14:07:18.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:105:0)
[14:07:18.015] Timer tick, asking Buildbucket for the build status
[14:07:18.108] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:18.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:106:0) after 1m0s
[14:08:18.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:106:0)
[14:08:18.200] Timer tick, asking Buildbucket for the build status
[14:08:18.267] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:18.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:107:0) after 1m0s
[14:09:18.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:107:0)
[14:09:18.328] Timer tick, asking Buildbucket for the build status
[14:09:18.391] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:18.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:108:0) after 1m0s
[14:10:18.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:108:0)
[14:10:18.498] Timer tick, asking Buildbucket for the build status
[14:10:18.715] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:18.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:109:0) after 1m0s
[14:11:18.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:109:0)
[14:11:18.735] Timer tick, asking Buildbucket for the build status
[14:11:18.805] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:18.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:110:0) after 1m0s
[14:12:18.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:110:0)
[14:12:18.822] Timer tick, asking Buildbucket for the build status
[14:12:18.876] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:18.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:111:0) after 1m0s
[14:13:18.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:111:0)
[14:13:18.900] Timer tick, asking Buildbucket for the build status
[14:13:19.026] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:19.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:112:0) after 1m0s
[14:14:19.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:112:0)
[14:14:19.047] Timer tick, asking Buildbucket for the build status
[14:14:19.122] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:19.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:113:0) after 1m0s
[14:15:19.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:113:0)
[14:15:19.143] Timer tick, asking Buildbucket for the build status
[14:15:19.444] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:19.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:114:0) after 1m0s
[14:16:19.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:114:0)
[14:16:19.467] Timer tick, asking Buildbucket for the build status
[14:16:19.667] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:19.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:115:0) after 1m0s
[14:17:19.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:115:0)
[14:17:19.690] Timer tick, asking Buildbucket for the build status
[14:17:19.748] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:19.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:116:0) after 1m0s
[14:18:19.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:116:0)
[14:18:19.769] Timer tick, asking Buildbucket for the build status
[14:18:19.830] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:19.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:117:0) after 1m0s
[14:19:19.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:117:0)
[14:19:19.853] Timer tick, asking Buildbucket for the build status
[14:19:19.931] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:19.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:118:0) after 1m0s
[14:20:19.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:118:0)
[14:20:19.953] Timer tick, asking Buildbucket for the build status
[14:20:20.000] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:20.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:119:0) after 1m0s
[14:21:20.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:119:0)
[14:21:20.006] Timer tick, asking Buildbucket for the build status
[14:21:20.092] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:20.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:120:0) after 1m0s
[14:22:20.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:120:0)
[14:22:20.183] Timer tick, asking Buildbucket for the build status
[14:22:20.296] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:20.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:121:0) after 1m0s
[14:23:20.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:121:0)
[14:23:20.343] Timer tick, asking Buildbucket for the build status
[14:23:20.407] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:20.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:122:0) after 1m0s
[14:24:20.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:122:0)
[14:24:20.404] Timer tick, asking Buildbucket for the build status
[14:24:20.524] Build 8916201514997100480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:20.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081631576989654416:123:0) after 1m0s
[14:24:20.835] Received PubSub notification, asking Buildbucket for the build status
[14:24:20.952] Build 8916201514997100480: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:24:20.952] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555251860587005",
"created_by": "project:fuchsia",
"created_ts": "1555244637452002",
"failure_reason": "BUILD_FAILURE",
"id": "8916201514997100480",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b1b580e047ad87914585747c908263ae3131d48e\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-4kw8\", \"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\": \"b1b580e047ad87914585747c908263ae3131d48e\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-4kw8\"], \"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\": [\"4253-3d44409\"], \"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": "1555244638955943",
"status": "COMPLETED",
"status_changed_ts": "1555244639353716",
"tags": [
"builder:clang",
"buildset:commit/git/b1b580e047ad87914585747c908263ae3131d48e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b1b580e047ad87914585747c908263ae3131d48e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081631576989654416",
"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/8916201514997100480/+/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:44349f69a3297910",
"user_agent:luci-scheduler"
],
"updated_ts": "1555251860631241",
"url": "https://ci.chromium.org/b/8916201514997100480",
"utcnow_ts": "1555251860940465"
}
}
[14:24:20.952] Invocation finished in 2h0m24.784071531s with status FAILED