[03:04:28.109] New invocation is queued and will start shortly
[03:04:29.219] Starting the invocation (attempt 1)
[03:04:29.299] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:04:29.299] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:04:29.299] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078767672751790624",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"69e927662dc959ad4fd057619f6511bb65537213\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078767672751790624",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/69e927662dc959ad4fd057619f6511bb65537213",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/69e927662dc959ad4fd057619f6511bb65537213",
"gitiles_ref:refs/heads/master"
]
}
[03:04:30.276] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557975869422975",
"id": "8913337610703050176",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"69e927662dc959ad4fd057619f6511bb65537213\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557975869828208",
"tags": [
"builder:clang",
"buildset:commit/git/69e927662dc959ad4fd057619f6511bb65537213",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/69e927662dc959ad4fd057619f6511bb65537213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078767672751790624",
"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/8913337610703050176/+/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": "1557975869828310",
"url": "https://ci.chromium.org/b/8913337610703050176",
"utcnow_ts": "1557975870253962"
}
}
[03:04:30.276] Task URL: https://ci.chromium.org/b/8913337610703050176
[03:04:30.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:2:0) after 1m0s
[03:04:31.909] Received PubSub notification, asking Buildbucket for the build status
[03:04:32.118] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:30.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:2:0)
[03:05:30.307] Timer tick, asking Buildbucket for the build status
[03:05:30.436] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:30.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:4:0) after 1m0s
[03:06:30.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:4:0)
[03:06:30.455] Timer tick, asking Buildbucket for the build status
[03:06:30.519] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:30.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:5:0) after 1m0s
[03:07:30.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:5:0)
[03:07:30.518] Timer tick, asking Buildbucket for the build status
[03:07:30.625] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:30.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:6:0) after 1m0s
[03:08:30.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:6:0)
[03:08:30.839] Timer tick, asking Buildbucket for the build status
[03:08:30.980] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:30.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:7:0) after 1m0s
[03:09:30.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:7:0)
[03:09:30.982] Timer tick, asking Buildbucket for the build status
[03:09:31.087] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:31.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:8:0) after 1m0s
[03:10:31.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:8:0)
[03:10:31.098] Timer tick, asking Buildbucket for the build status
[03:10:31.151] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:31.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:9:0) after 1m0s
[03:11:31.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:9:0)
[03:11:31.151] Timer tick, asking Buildbucket for the build status
[03:11:31.294] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:31.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:10:0) after 1m0s
[03:12:31.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:10:0)
[03:12:31.764] Timer tick, asking Buildbucket for the build status
[03:12:32.545] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:32.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:11:0) after 1m0s
[03:13:32.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:11:0)
[03:13:32.592] Timer tick, asking Buildbucket for the build status
[03:13:32.660] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:32.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:12:0) after 1m0s
[03:14:32.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:12:0)
[03:14:32.706] Timer tick, asking Buildbucket for the build status
[03:14:32.773] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:32.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:13:0) after 1m0s
[03:15:32.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:13:0)
[03:15:32.796] Timer tick, asking Buildbucket for the build status
[03:15:32.844] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:32.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:14:0) after 1m0s
[03:16:33.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:14:0)
[03:16:33.023] Timer tick, asking Buildbucket for the build status
[03:16:33.145] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:33.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:15:0) after 1m0s
[03:17:33.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:15:0)
[03:17:33.490] Timer tick, asking Buildbucket for the build status
[03:17:33.548] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:33.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:16:0) after 1m0s
[03:18:33.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:16:0)
[03:18:33.888] Timer tick, asking Buildbucket for the build status
[03:18:34.093] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:34.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:17:0) after 1m0s
[03:19:34.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:17:0)
[03:19:34.253] Timer tick, asking Buildbucket for the build status
[03:19:34.407] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:34.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:18:0) after 1m0s
[03:20:34.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:18:0)
[03:20:34.388] Timer tick, asking Buildbucket for the build status
[03:20:34.446] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:34.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:19:0) after 1m0s
[03:21:34.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:19:0)
[03:21:34.466] Timer tick, asking Buildbucket for the build status
[03:21:34.844] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:34.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:20:0) after 1m0s
[03:22:34.868] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:20:0)
[03:22:34.868] Timer tick, asking Buildbucket for the build status
[03:22:34.920] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:34.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:21:0) after 1m0s
[03:23:34.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:21:0)
[03:23:34.923] Timer tick, asking Buildbucket for the build status
[03:23:35.143] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:35.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:22:0) after 1m0s
[03:24:35.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:22:0)
[03:24:35.168] Timer tick, asking Buildbucket for the build status
[03:24:35.226] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:35.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:23:0) after 1m0s
[03:25:35.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:23:0)
[03:25:35.286] Timer tick, asking Buildbucket for the build status
[03:25:35.346] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:35.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:24:0) after 1m0s
[03:26:35.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:24:0)
[03:26:35.432] Timer tick, asking Buildbucket for the build status
[03:26:35.631] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:35.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:25:0) after 1m0s
[03:27:35.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:25:0)
[03:27:35.664] Timer tick, asking Buildbucket for the build status
[03:27:35.728] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:35.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:26:0) after 1m0s
[03:28:35.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:26:0)
[03:28:35.858] Timer tick, asking Buildbucket for the build status
[03:28:36.019] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:36.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:27:0) after 1m0s
[03:29:36.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:27:0)
[03:29:36.094] Timer tick, asking Buildbucket for the build status
[03:29:36.256] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:36.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:28:0) after 1m0s
[03:30:36.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:28:0)
[03:30:36.243] Timer tick, asking Buildbucket for the build status
[03:30:36.444] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:36.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:29:0) after 1m0s
[03:31:36.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:29:0)
[03:31:36.475] Timer tick, asking Buildbucket for the build status
[03:31:37.502] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:37.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:30:0) after 1m0s
[03:32:37.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:30:0)
[03:32:37.532] Timer tick, asking Buildbucket for the build status
[03:32:37.740] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:37.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:31:0) after 1m0s
[03:33:37.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:31:0)
[03:33:37.768] Timer tick, asking Buildbucket for the build status
[03:33:37.969] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:37.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:32:0) after 1m0s
[03:34:38.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:32:0)
[03:34:38.007] Timer tick, asking Buildbucket for the build status
[03:34:38.096] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:38.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:33:0) after 1m0s
[03:35:38.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:33:0)
[03:35:38.364] Timer tick, asking Buildbucket for the build status
[03:35:38.550] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:38.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:34:0) after 1m0s
[03:36:38.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:34:0)
[03:36:38.761] Timer tick, asking Buildbucket for the build status
[03:36:38.933] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:38.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:35:0) after 1m0s
[03:37:39.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:35:0)
[03:37:39.261] Timer tick, asking Buildbucket for the build status
[03:37:39.472] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:39.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:36:0) after 1m0s
[03:38:39.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:36:0)
[03:38:39.463] Timer tick, asking Buildbucket for the build status
[03:38:39.581] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:39.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:37:0) after 1m0s
[03:39:39.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:37:0)
[03:39:39.650] Timer tick, asking Buildbucket for the build status
[03:39:39.732] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:39.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:38:0) after 1m0s
[03:40:39.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:38:0)
[03:40:39.785] Timer tick, asking Buildbucket for the build status
[03:40:39.920] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:39.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:39:0) after 1m0s
[03:41:39.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:39:0)
[03:41:39.943] Timer tick, asking Buildbucket for the build status
[03:41:40.093] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:40.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:40:0) after 1m0s
[03:42:40.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:40:0)
[03:42:40.114] Timer tick, asking Buildbucket for the build status
[03:42:40.186] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:40.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:41:0) after 1m0s
[03:43:40.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:41:0)
[03:43:40.245] Timer tick, asking Buildbucket for the build status
[03:43:40.285] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:40.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:42:0) after 1m0s
[03:44:40.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:42:0)
[03:44:40.312] Timer tick, asking Buildbucket for the build status
[03:44:40.362] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:40.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:43:0) after 1m0s
[03:45:40.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:43:0)
[03:45:40.383] Timer tick, asking Buildbucket for the build status
[03:45:40.475] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:40.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:44:0) after 1m0s
[03:46:40.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:44:0)
[03:46:40.491] Timer tick, asking Buildbucket for the build status
[03:46:40.542] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:40.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:45:0) after 1m0s
[03:47:40.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:45:0)
[03:47:40.544] Timer tick, asking Buildbucket for the build status
[03:47:40.652] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:40.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:46:0) after 1m0s
[03:48:40.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:46:0)
[03:48:40.668] Timer tick, asking Buildbucket for the build status
[03:48:40.750] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:40.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:47:0) after 1m0s
[03:49:40.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:47:0)
[03:49:40.792] Timer tick, asking Buildbucket for the build status
[03:49:40.942] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:40.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:48:0) after 1m0s
[03:50:40.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:48:0)
[03:50:40.973] Timer tick, asking Buildbucket for the build status
[03:50:41.272] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:41.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:49:0) after 1m0s
[03:51:41.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:49:0)
[03:51:41.318] Timer tick, asking Buildbucket for the build status
[03:51:42.474] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:42.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:50:0) after 1m0s
[03:52:42.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:50:0)
[03:52:42.475] Timer tick, asking Buildbucket for the build status
[03:52:42.522] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:42.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:51:0) after 1m0s
[03:53:42.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:51:0)
[03:53:42.555] Timer tick, asking Buildbucket for the build status
[03:53:42.623] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:42.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:52:0) after 1m0s
[03:54:42.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:52:0)
[03:54:42.668] Timer tick, asking Buildbucket for the build status
[03:54:42.708] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:42.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:53:0) after 1m0s
[03:55:42.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:53:0)
[03:55:42.729] Timer tick, asking Buildbucket for the build status
[03:55:42.807] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:42.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:54:0) after 1m0s
[03:56:42.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:54:0)
[03:56:42.796] Timer tick, asking Buildbucket for the build status
[03:56:42.872] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:42.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:55:0) after 1m0s
[03:57:42.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:55:0)
[03:57:42.909] Timer tick, asking Buildbucket for the build status
[03:57:42.960] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:42.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:56:0) after 1m0s
[03:58:42.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:56:0)
[03:58:42.944] Timer tick, asking Buildbucket for the build status
[03:58:43.014] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:43.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:57:0) after 1m0s
[03:59:43.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:57:0)
[03:59:43.035] Timer tick, asking Buildbucket for the build status
[03:59:43.150] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:43.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:58:0) after 1m0s
[04:00:43.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:58:0)
[04:00:43.171] Timer tick, asking Buildbucket for the build status
[04:00:43.277] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:43.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:59:0) after 1m0s
[04:01:43.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:59:0)
[04:01:43.325] Timer tick, asking Buildbucket for the build status
[04:01:43.397] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:43.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:60:0) after 1m0s
[04:02:43.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:60:0)
[04:02:43.415] Timer tick, asking Buildbucket for the build status
[04:02:43.889] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:43.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:61:0) after 1m0s
[04:03:43.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:61:0)
[04:03:43.921] Timer tick, asking Buildbucket for the build status
[04:03:44.047] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:44.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:62:0) after 1m0s
[04:04:44.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:62:0)
[04:04:44.071] Timer tick, asking Buildbucket for the build status
[04:04:44.172] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:44.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:63:0) after 1m0s
[04:05:44.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:63:0)
[04:05:44.239] Timer tick, asking Buildbucket for the build status
[04:05:44.326] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:44.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:64:0) after 1m0s
[04:06:44.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:64:0)
[04:06:44.377] Timer tick, asking Buildbucket for the build status
[04:06:44.425] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:44.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:65:0) after 1m0s
[04:07:44.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:65:0)
[04:07:44.485] Timer tick, asking Buildbucket for the build status
[04:07:44.531] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:44.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:66:0) after 1m0s
[04:08:44.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:66:0)
[04:08:44.556] Timer tick, asking Buildbucket for the build status
[04:08:44.666] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:44.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:67:0) after 1m0s
[04:09:44.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:67:0)
[04:09:44.664] Timer tick, asking Buildbucket for the build status
[04:09:45.785] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:45.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:68:0) after 1m0s
[04:10:45.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:68:0)
[04:10:45.801] Timer tick, asking Buildbucket for the build status
[04:10:45.889] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:45.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:69:0) after 1m0s
[04:11:45.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:69:0)
[04:11:45.891] Timer tick, asking Buildbucket for the build status
[04:11:46.056] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:46.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:70:0) after 1m0s
[04:12:46.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:70:0)
[04:12:46.105] Timer tick, asking Buildbucket for the build status
[04:12:46.168] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:46.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:71:0) after 1m0s
[04:13:46.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:71:0)
[04:13:46.214] Timer tick, asking Buildbucket for the build status
[04:13:46.354] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:46.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:72:0) after 1m0s
[04:14:46.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:72:0)
[04:14:46.386] Timer tick, asking Buildbucket for the build status
[04:14:46.451] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:46.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:73:0) after 1m0s
[04:15:46.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:73:0)
[04:15:46.481] Timer tick, asking Buildbucket for the build status
[04:15:46.641] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:46.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:74:0) after 1m0s
[04:16:46.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:74:0)
[04:16:46.669] Timer tick, asking Buildbucket for the build status
[04:16:46.814] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:46.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:75:0) after 1m0s
[04:17:46.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:75:0)
[04:17:46.906] Timer tick, asking Buildbucket for the build status
[04:17:46.995] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:46.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:76:0) after 1m0s
[04:18:47.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:76:0)
[04:18:47.036] Timer tick, asking Buildbucket for the build status
[04:18:47.090] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:47.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:77:0) after 1m0s
[04:19:47.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:77:0)
[04:19:47.130] Timer tick, asking Buildbucket for the build status
[04:19:47.202] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:47.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:78:0) after 1m0s
[04:20:47.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:78:0)
[04:20:47.222] Timer tick, asking Buildbucket for the build status
[04:20:47.269] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:47.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:79:0) after 1m0s
[04:21:47.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:79:0)
[04:21:47.290] Timer tick, asking Buildbucket for the build status
[04:21:47.363] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:47.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:80:0) after 1m0s
[04:22:47.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:80:0)
[04:22:47.867] Timer tick, asking Buildbucket for the build status
[04:22:47.969] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:47.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:81:0) after 1m0s
[04:23:47.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:81:0)
[04:23:47.945] Timer tick, asking Buildbucket for the build status
[04:23:48.068] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:48.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:82:0) after 1m0s
[04:24:48.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:82:0)
[04:24:48.091] Timer tick, asking Buildbucket for the build status
[04:24:48.157] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:48.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:83:0) after 1m0s
[04:25:48.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:83:0)
[04:25:48.206] Timer tick, asking Buildbucket for the build status
[04:25:48.248] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:48.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:84:0) after 1m0s
[04:26:48.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:84:0)
[04:26:48.490] Timer tick, asking Buildbucket for the build status
[04:26:48.616] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:48.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:85:0) after 1m0s
[04:27:48.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:85:0)
[04:27:48.645] Timer tick, asking Buildbucket for the build status
[04:27:48.760] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:48.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:86:0) after 1m0s
[04:28:48.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:86:0)
[04:28:48.765] Timer tick, asking Buildbucket for the build status
[04:28:48.838] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:48.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:87:0) after 1m0s
[04:29:48.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:87:0)
[04:29:48.954] Timer tick, asking Buildbucket for the build status
[04:29:49.020] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:49.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:88:0) after 1m0s
[04:30:49.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:88:0)
[04:30:49.097] Timer tick, asking Buildbucket for the build status
[04:30:49.246] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:49.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:89:0) after 1m0s
[04:31:49.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:89:0)
[04:31:49.224] Timer tick, asking Buildbucket for the build status
[04:31:49.352] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:90:0) after 1m0s
[04:32:49.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:90:0)
[04:32:49.415] Timer tick, asking Buildbucket for the build status
[04:32:49.778] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:91:0) after 1m0s
[04:33:49.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:91:0)
[04:33:49.804] Timer tick, asking Buildbucket for the build status
[04:33:49.847] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:92:0) after 1m0s
[04:34:49.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:92:0)
[04:34:49.875] Timer tick, asking Buildbucket for the build status
[04:34:49.915] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:49.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:93:0) after 1m0s
[04:35:49.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:93:0)
[04:35:49.947] Timer tick, asking Buildbucket for the build status
[04:35:50.032] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:50.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:94:0) after 1m0s
[04:36:50.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:94:0)
[04:36:50.063] Timer tick, asking Buildbucket for the build status
[04:36:50.255] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:50.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:95:0) after 1m0s
[04:37:50.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:95:0)
[04:37:50.269] Timer tick, asking Buildbucket for the build status
[04:37:50.473] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:50.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:96:0) after 1m0s
[04:38:50.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:96:0)
[04:38:50.517] Timer tick, asking Buildbucket for the build status
[04:38:50.570] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:50.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:97:0) after 1m0s
[04:39:50.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:97:0)
[04:39:50.595] Timer tick, asking Buildbucket for the build status
[04:39:50.760] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:50.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:98:0) after 1m0s
[04:40:50.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:98:0)
[04:40:50.789] Timer tick, asking Buildbucket for the build status
[04:40:51.317] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:51.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:99:0) after 1m0s
[04:41:51.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:99:0)
[04:41:51.345] Timer tick, asking Buildbucket for the build status
[04:41:51.480] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:51.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:100:0) after 1m0s
[04:42:51.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:100:0)
[04:42:51.521] Timer tick, asking Buildbucket for the build status
[04:42:51.833] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:51.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:101:0) after 1m0s
[04:43:51.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:101:0)
[04:43:51.859] Timer tick, asking Buildbucket for the build status
[04:43:52.048] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:52.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:102:0) after 1m0s
[04:44:52.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:102:0)
[04:44:52.074] Timer tick, asking Buildbucket for the build status
[04:44:52.198] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:52.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:103:0) after 1m0s
[04:45:52.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:103:0)
[04:45:52.209] Timer tick, asking Buildbucket for the build status
[04:45:52.278] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:52.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:104:0) after 1m0s
[04:46:52.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:104:0)
[04:46:52.308] Timer tick, asking Buildbucket for the build status
[04:46:52.384] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:52.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:105:0) after 1m0s
[04:47:52.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:105:0)
[04:47:52.453] Timer tick, asking Buildbucket for the build status
[04:47:52.529] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:52.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:106:0) after 1m0s
[04:48:52.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:106:0)
[04:48:52.598] Timer tick, asking Buildbucket for the build status
[04:48:52.681] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:52.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:107:0) after 1m0s
[04:49:52.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:107:0)
[04:49:52.675] Timer tick, asking Buildbucket for the build status
[04:49:52.801] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:52.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:108:0) after 1m0s
[04:50:52.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:108:0)
[04:50:52.834] Timer tick, asking Buildbucket for the build status
[04:50:52.892] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:52.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:109:0) after 1m0s
[04:51:52.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:109:0)
[04:51:52.875] Timer tick, asking Buildbucket for the build status
[04:51:52.960] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:52.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:110:0) after 1m0s
[04:52:53.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:110:0)
[04:52:53.006] Timer tick, asking Buildbucket for the build status
[04:52:53.098] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:53.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:111:0) after 1m0s
[04:53:53.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:111:0)
[04:53:53.135] Timer tick, asking Buildbucket for the build status
[04:53:53.204] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:53.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:112:0) after 1m0s
[04:54:53.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:112:0)
[04:54:53.236] Timer tick, asking Buildbucket for the build status
[04:54:53.394] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:53.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:113:0) after 1m0s
[04:55:53.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:113:0)
[04:55:53.430] Timer tick, asking Buildbucket for the build status
[04:55:53.529] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:53.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:114:0) after 1m0s
[04:56:53.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:114:0)
[04:56:53.568] Timer tick, asking Buildbucket for the build status
[04:56:53.673] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:53.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:115:0) after 1m0s
[04:57:53.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:115:0)
[04:57:53.720] Timer tick, asking Buildbucket for the build status
[04:57:53.801] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:53.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:116:0) after 1m0s
[04:58:53.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:116:0)
[04:58:53.803] Timer tick, asking Buildbucket for the build status
[04:58:53.976] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:53.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:117:0) after 1m0s
[04:59:54.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:117:0)
[04:59:54.008] Timer tick, asking Buildbucket for the build status
[04:59:54.083] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:54.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:118:0) after 1m0s
[05:00:54.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:118:0)
[05:00:54.118] Timer tick, asking Buildbucket for the build status
[05:00:54.285] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:54.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:119:0) after 1m0s
[05:01:54.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:119:0)
[05:01:54.321] Timer tick, asking Buildbucket for the build status
[05:01:54.397] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:54.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:120:0) after 1m0s
[05:02:54.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:120:0)
[05:02:54.387] Timer tick, asking Buildbucket for the build status
[05:02:54.437] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:54.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:121:0) after 1m0s
[05:03:54.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:121:0)
[05:03:54.563] Timer tick, asking Buildbucket for the build status
[05:03:54.812] Build 8913337610703050176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:54.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078767672751790624:122:0) after 1m0s
[05:04:53.834] Received PubSub notification, asking Buildbucket for the build status
[05:04:53.904] Build 8913337610703050176: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:04:53.904] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557983092980933",
"created_by": "project:fuchsia",
"created_ts": "1557975869422975",
"failure_reason": "BUILD_FAILURE",
"id": "8913337610703050176",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"69e927662dc959ad4fd057619f6511bb65537213\"}}",
"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-n5ls\", \"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\": \"69e927662dc959ad4fd057619f6511bb65537213\", \"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-prod-1-us-central1-b-0-n5ls\"], \"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\": [\"4300-ce42b68\"], \"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": "1557975870972089",
"status": "COMPLETED",
"status_changed_ts": "1557975871647846",
"tags": [
"builder:clang",
"buildset:commit/git/69e927662dc959ad4fd057619f6511bb65537213",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/69e927662dc959ad4fd057619f6511bb65537213",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078767672751790624",
"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/8913337610703050176/+/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:44d76ab3234e3b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557983093029825",
"url": "https://ci.chromium.org/b/8913337610703050176",
"utcnow_ts": "1557983093892344"
}
}
[05:04:53.904] Invocation finished in 2h0m25.819039184s with status FAILED