[09:28:30.426] New invocation is queued and will start shortly
[09:28:31.508] Starting the invocation (attempt 1)
[09:28:31.546] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:28:31.546] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:28:31.546] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9054191733227738096",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\",\"title\":\"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"642bc15dd7186a6317510cf961a8dc3d35e5b713\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@642bc15dd7186a6317510cf961a8dc3d35e5b713\",\"title\":\"642bc15dd7186a6317510cf961a8dc3d35e5b713\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/642bc15dd7186a6317510cf961a8dc3d35e5b713\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"487621237d2fe5a53ac40a29f6443d3e4f106c61\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@487621237d2fe5a53ac40a29f6443d3e4f106c61\",\"title\":\"487621237d2fe5a53ac40a29f6443d3e4f106c61\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/487621237d2fe5a53ac40a29f6443d3e4f106c61\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a7fd548a4f9e50e1aa2def57bae885604c9c5878\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@a7fd548a4f9e50e1aa2def57bae885604c9c5878\",\"title\":\"a7fd548a4f9e50e1aa2def57bae885604c9c5878\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/a7fd548a4f9e50e1aa2def57bae885604c9c5878\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"},\"id\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@2733ad2c244ab33553bbd1aaca4c76907fdd0760\",\"title\":\"2733ad2c244ab33553bbd1aaca4c76907fdd0760\",\"url\":\"https://fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9054191733227738096",
"scheduler_job_id:fuchsia/clang",
"user_agent:luci-scheduler",
"buildset:commit/git/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"gitiles_ref:refs/heads/master"
]
}
[09:28:32.112] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1581413311632319",
"id": "8888761671300145472",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"title\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"title\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"title\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"title\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"title\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581413311958537",
"tags": [
"builder:clang",
"buildset:commit/git/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054191733227738096",
"scheduler_job_id:fuchsia/clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8888761671300145472/+/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": "1581413311958680",
"url": "https://ci.chromium.org/b/8888761671300145472",
"utcnow_ts": "1581413312102303"
}
}
[09:28:32.112] Task URL: https://ci.chromium.org/b/8888761671300145472
[09:28:32.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:2:0) after 1m0s
[09:28:37.714] Received PubSub notification, asking Buildbucket for the build status
[09:28:37.790] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:32.135] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:2:0)
[09:29:32.135] Timer tick, asking Buildbucket for the build status
[09:29:32.424] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:32.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:4:0) after 1m0s
[09:30:32.443] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:4:0)
[09:30:32.443] Timer tick, asking Buildbucket for the build status
[09:30:33.044] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:33.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:5:0) after 1m0s
[09:31:33.113] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:5:0)
[09:31:33.113] Timer tick, asking Buildbucket for the build status
[09:31:33.259] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:33.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:6:0) after 1m0s
[09:32:33.283] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:6:0)
[09:32:33.283] Timer tick, asking Buildbucket for the build status
[09:32:33.791] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:33.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:7:0) after 1m0s
[09:33:33.817] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:7:0)
[09:33:33.817] Timer tick, asking Buildbucket for the build status
[09:33:34.259] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:34.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:8:0) after 1m0s
[09:34:34.341] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:8:0)
[09:34:34.341] Timer tick, asking Buildbucket for the build status
[09:34:34.746] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:34.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:9:0) after 1m0s
[09:35:34.860] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:9:0)
[09:35:34.860] Timer tick, asking Buildbucket for the build status
[09:35:35.116] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:35.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:10:0) after 1m0s
[09:36:36.982] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:10:0)
[09:36:36.982] Timer tick, asking Buildbucket for the build status
[09:36:37.196] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:37.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:11:0) after 1m0s
[09:37:37.463] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:11:0)
[09:37:37.463] Timer tick, asking Buildbucket for the build status
[09:37:37.581] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:37.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:12:0) after 1m0s
[09:38:37.602] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:12:0)
[09:38:37.602] Timer tick, asking Buildbucket for the build status
[09:38:37.880] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:37.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:13:0) after 1m0s
[09:39:37.914] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:13:0)
[09:39:37.914] Timer tick, asking Buildbucket for the build status
[09:39:38.040] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:38.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:14:0) after 1m0s
[09:40:38.114] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:14:0)
[09:40:38.114] Timer tick, asking Buildbucket for the build status
[09:40:38.240] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:38.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:15:0) after 1m0s
[09:41:38.345] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:15:0)
[09:41:38.345] Timer tick, asking Buildbucket for the build status
[09:41:38.411] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:38.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:16:0) after 1m0s
[09:42:38.433] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:16:0)
[09:42:38.433] Timer tick, asking Buildbucket for the build status
[09:42:38.610] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:38.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:17:0) after 1m0s
[09:43:38.641] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:17:0)
[09:43:38.641] Timer tick, asking Buildbucket for the build status
[09:43:38.785] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:38.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:18:0) after 1m0s
[09:44:38.860] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:18:0)
[09:44:38.860] Timer tick, asking Buildbucket for the build status
[09:44:38.970] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:38.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:19:0) after 1m0s
[09:45:39.201] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:19:0)
[09:45:39.201] Timer tick, asking Buildbucket for the build status
[09:45:39.654] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:39.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:20:0) after 1m0s
[09:46:39.730] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:20:0)
[09:46:39.730] Timer tick, asking Buildbucket for the build status
[09:46:39.878] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:39.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:21:0) after 1m0s
[09:47:39.987] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:21:0)
[09:47:39.987] Timer tick, asking Buildbucket for the build status
[09:47:40.154] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:40.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:22:0) after 1m0s
[09:48:40.225] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:22:0)
[09:48:40.225] Timer tick, asking Buildbucket for the build status
[09:48:40.377] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:40.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:23:0) after 1m0s
[09:49:40.498] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:23:0)
[09:49:40.498] Timer tick, asking Buildbucket for the build status
[09:49:40.625] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:40.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:24:0) after 1m0s
[09:50:40.835] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:24:0)
[09:50:40.835] Timer tick, asking Buildbucket for the build status
[09:50:41.214] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:25:0) after 1m0s
[09:51:41.280] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:25:0)
[09:51:41.280] Timer tick, asking Buildbucket for the build status
[09:51:41.399] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:41.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:26:0) after 1m0s
[09:52:41.423] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:26:0)
[09:52:41.423] Timer tick, asking Buildbucket for the build status
[09:52:41.678] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:41.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:27:0) after 1m0s
[09:53:41.741] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:27:0)
[09:53:41.741] Timer tick, asking Buildbucket for the build status
[09:53:42.462] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:42.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:28:0) after 1m0s
[09:54:42.521] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:28:0)
[09:54:42.521] Timer tick, asking Buildbucket for the build status
[09:54:43.225] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:43.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:29:0) after 1m0s
[09:55:43.249] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:29:0)
[09:55:43.249] Timer tick, asking Buildbucket for the build status
[09:55:43.405] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:43.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:30:0) after 1m0s
[09:56:43.427] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:30:0)
[09:56:43.427] Timer tick, asking Buildbucket for the build status
[09:56:43.537] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:43.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:31:0) after 1m0s
[09:57:43.682] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:31:0)
[09:57:43.682] Timer tick, asking Buildbucket for the build status
[09:57:45.532] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:45.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:32:0) after 1m0s
[09:58:45.555] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:32:0)
[09:58:45.555] Timer tick, asking Buildbucket for the build status
[09:58:45.765] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:45.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:33:0) after 1m0s
[09:59:45.859] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:33:0)
[09:59:45.859] Timer tick, asking Buildbucket for the build status
[09:59:45.986] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:45.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:34:0) after 1m0s
[10:00:46.042] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:34:0)
[10:00:46.042] Timer tick, asking Buildbucket for the build status
[10:00:46.187] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:46.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:35:0) after 1m0s
[10:01:46.213] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:35:0)
[10:01:46.213] Timer tick, asking Buildbucket for the build status
[10:01:46.477] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:46.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:36:0) after 1m0s
[10:02:46.545] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:36:0)
[10:02:46.545] Timer tick, asking Buildbucket for the build status
[10:02:46.849] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:46.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:37:0) after 1m0s
[10:03:46.919] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:37:0)
[10:03:46.919] Timer tick, asking Buildbucket for the build status
[10:03:47.055] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:47.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:38:0) after 1m0s
[10:04:47.087] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:38:0)
[10:04:47.087] Timer tick, asking Buildbucket for the build status
[10:04:47.247] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:47.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:39:0) after 1m0s
[10:05:47.337] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:39:0)
[10:05:47.337] Timer tick, asking Buildbucket for the build status
[10:05:47.491] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:47.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:40:0) after 1m0s
[10:06:47.516] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:40:0)
[10:06:47.516] Timer tick, asking Buildbucket for the build status
[10:06:47.675] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:47.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:41:0) after 1m0s
[10:07:47.733] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:41:0)
[10:07:47.734] Timer tick, asking Buildbucket for the build status
[10:07:47.892] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:47.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:42:0) after 1m0s
[10:08:47.916] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:42:0)
[10:08:47.916] Timer tick, asking Buildbucket for the build status
[10:08:48.430] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:48.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:43:0) after 1m0s
[10:09:48.455] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:43:0)
[10:09:48.455] Timer tick, asking Buildbucket for the build status
[10:09:48.579] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:48.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:44:0) after 1m0s
[10:10:48.641] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:44:0)
[10:10:48.641] Timer tick, asking Buildbucket for the build status
[10:10:48.767] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:48.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:45:0) after 1m0s
[10:11:48.821] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:45:0)
[10:11:48.821] Timer tick, asking Buildbucket for the build status
[10:11:49.041] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:49.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:46:0) after 1m0s
[10:12:49.108] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:46:0)
[10:12:49.108] Timer tick, asking Buildbucket for the build status
[10:12:49.325] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:49.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:47:0) after 1m0s
[10:13:49.350] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:47:0)
[10:13:49.350] Timer tick, asking Buildbucket for the build status
[10:13:49.472] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:49.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:48:0) after 1m0s
[10:14:49.571] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:48:0)
[10:14:49.571] Timer tick, asking Buildbucket for the build status
[10:14:49.698] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:49.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:49:0) after 1m0s
[10:15:49.794] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:49:0)
[10:15:49.794] Timer tick, asking Buildbucket for the build status
[10:15:49.983] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:49.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:50:0) after 1m0s
[10:16:50.082] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:50:0)
[10:16:50.082] Timer tick, asking Buildbucket for the build status
[10:16:50.255] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:50.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:51:0) after 1m0s
[10:17:50.280] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:51:0)
[10:17:50.280] Timer tick, asking Buildbucket for the build status
[10:17:50.397] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:50.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:52:0) after 1m0s
[10:18:50.425] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:52:0)
[10:18:50.425] Timer tick, asking Buildbucket for the build status
[10:18:50.993] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:50.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:53:0) after 1m0s
[10:19:51.083] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:53:0)
[10:19:51.083] Timer tick, asking Buildbucket for the build status
[10:19:51.501] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:51.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:54:0) after 1m0s
[10:20:51.534] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:54:0)
[10:20:51.534] Timer tick, asking Buildbucket for the build status
[10:20:51.749] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:51.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:55:0) after 1m0s
[10:21:51.777] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:55:0)
[10:21:51.777] Timer tick, asking Buildbucket for the build status
[10:21:51.963] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:51.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:56:0) after 1m0s
[10:22:51.983] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:56:0)
[10:22:51.983] Timer tick, asking Buildbucket for the build status
[10:22:53.013] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:53.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:57:0) after 1m0s
[10:23:53.091] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:57:0)
[10:23:53.091] Timer tick, asking Buildbucket for the build status
[10:23:53.204] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:53.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:58:0) after 1m0s
[10:24:53.200] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:58:0)
[10:24:53.200] Timer tick, asking Buildbucket for the build status
[10:24:53.319] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:53.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:59:0) after 1m0s
[10:25:53.444] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:59:0)
[10:25:53.444] Timer tick, asking Buildbucket for the build status
[10:25:53.624] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:53.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:60:0) after 1m0s
[10:26:53.737] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:60:0)
[10:26:53.737] Timer tick, asking Buildbucket for the build status
[10:26:54.067] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:54.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:61:0) after 1m0s
[10:27:54.118] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:61:0)
[10:27:54.118] Timer tick, asking Buildbucket for the build status
[10:27:54.259] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:54.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:62:0) after 1m0s
[10:28:54.471] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:62:0)
[10:28:54.471] Timer tick, asking Buildbucket for the build status
[10:28:54.633] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:54.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:63:0) after 1m0s
[10:29:54.657] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:63:0)
[10:29:54.657] Timer tick, asking Buildbucket for the build status
[10:29:55.253] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:55.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:64:0) after 1m0s
[10:30:55.300] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:64:0)
[10:30:55.300] Timer tick, asking Buildbucket for the build status
[10:30:55.421] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:55.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:65:0) after 1m0s
[10:31:55.469] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:65:0)
[10:31:55.469] Timer tick, asking Buildbucket for the build status
[10:31:55.651] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:55.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:66:0) after 1m0s
[10:32:55.749] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:66:0)
[10:32:55.749] Timer tick, asking Buildbucket for the build status
[10:32:55.911] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:55.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:67:0) after 1m0s
[10:33:55.938] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:67:0)
[10:33:55.938] Timer tick, asking Buildbucket for the build status
[10:33:56.135] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:56.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:68:0) after 1m0s
[10:34:56.288] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:68:0)
[10:34:56.288] Timer tick, asking Buildbucket for the build status
[10:34:56.456] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:56.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:69:0) after 1m0s
[10:35:56.933] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:69:0)
[10:35:56.933] Timer tick, asking Buildbucket for the build status
[10:35:57.202] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:57.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:70:0) after 1m0s
[10:36:57.228] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:70:0)
[10:36:57.228] Timer tick, asking Buildbucket for the build status
[10:36:57.389] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:57.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:71:0) after 1m0s
[10:37:57.468] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:71:0)
[10:37:57.468] Timer tick, asking Buildbucket for the build status
[10:37:57.582] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:57.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:72:0) after 1m0s
[10:38:57.678] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:72:0)
[10:38:57.678] Timer tick, asking Buildbucket for the build status
[10:38:57.909] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:57.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:73:0) after 1m0s
[10:39:57.981] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:73:0)
[10:39:57.981] Timer tick, asking Buildbucket for the build status
[10:39:58.198] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:58.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:74:0) after 1m0s
[10:40:58.177] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:74:0)
[10:40:58.177] Timer tick, asking Buildbucket for the build status
[10:40:58.392] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:58.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:75:0) after 1m0s
[10:41:58.385] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:75:0)
[10:41:58.385] Timer tick, asking Buildbucket for the build status
[10:41:58.540] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:58.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:76:0) after 1m0s
[10:42:58.662] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:76:0)
[10:42:58.662] Timer tick, asking Buildbucket for the build status
[10:42:58.895] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:58.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:77:0) after 1m0s
[10:43:58.964] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:77:0)
[10:43:58.964] Timer tick, asking Buildbucket for the build status
[10:43:59.196] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:59.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:78:0) after 1m0s
[10:44:59.243] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:78:0)
[10:44:59.243] Timer tick, asking Buildbucket for the build status
[10:44:59.356] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:59.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:79:0) after 1m0s
[10:45:59.429] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:79:0)
[10:45:59.429] Timer tick, asking Buildbucket for the build status
[10:45:59.552] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:59.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:80:0) after 1m0s
[10:46:59.601] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:80:0)
[10:46:59.601] Timer tick, asking Buildbucket for the build status
[10:46:59.998] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:59.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:81:0) after 1m0s
[10:48:00.065] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:81:0)
[10:48:00.065] Timer tick, asking Buildbucket for the build status
[10:48:00.328] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:00.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:82:0) after 1m0s
[10:49:00.439] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:82:0)
[10:49:00.439] Timer tick, asking Buildbucket for the build status
[10:49:00.625] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:00.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:83:0) after 1m0s
[10:50:00.758] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:83:0)
[10:50:00.758] Timer tick, asking Buildbucket for the build status
[10:50:00.913] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:00.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:84:0) after 1m0s
[10:51:00.956] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:84:0)
[10:51:00.956] Timer tick, asking Buildbucket for the build status
[10:51:01.339] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:01.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:85:0) after 1m0s
[10:52:01.748] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:85:0)
[10:52:01.748] Timer tick, asking Buildbucket for the build status
[10:52:01.920] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:01.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:86:0) after 1m0s
[10:53:02.000] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:86:0)
[10:53:02.000] Timer tick, asking Buildbucket for the build status
[10:53:02.138] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:02.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:87:0) after 1m0s
[10:54:02.192] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:87:0)
[10:54:02.192] Timer tick, asking Buildbucket for the build status
[10:54:02.445] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:02.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:88:0) after 1m0s
[10:55:02.559] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:88:0)
[10:55:02.559] Timer tick, asking Buildbucket for the build status
[10:55:02.690] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:02.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:89:0) after 1m0s
[10:56:02.702] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:89:0)
[10:56:02.702] Timer tick, asking Buildbucket for the build status
[10:56:02.864] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:02.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:90:0) after 1m0s
[10:57:02.892] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:90:0)
[10:57:02.892] Timer tick, asking Buildbucket for the build status
[10:57:03.301] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:03.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:91:0) after 1m0s
[10:58:03.326] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:91:0)
[10:58:03.326] Timer tick, asking Buildbucket for the build status
[10:58:05.559] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:05.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:92:0) after 1m0s
[10:59:05.589] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:92:0)
[10:59:05.589] Timer tick, asking Buildbucket for the build status
[10:59:05.986] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:05.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:93:0) after 1m0s
[11:00:06.075] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:93:0)
[11:00:06.075] Timer tick, asking Buildbucket for the build status
[11:00:06.266] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:06.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:94:0) after 1m0s
[11:01:06.664] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:94:0)
[11:01:06.664] Timer tick, asking Buildbucket for the build status
[11:01:06.965] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:06.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:95:0) after 1m0s
[11:02:07.026] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:95:0)
[11:02:07.026] Timer tick, asking Buildbucket for the build status
[11:02:07.164] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:07.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:96:0) after 1m0s
[11:03:07.227] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:96:0)
[11:03:07.227] Timer tick, asking Buildbucket for the build status
[11:03:07.336] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:07.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:97:0) after 1m0s
[11:04:07.432] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:97:0)
[11:04:07.432] Timer tick, asking Buildbucket for the build status
[11:04:07.761] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:07.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:98:0) after 1m0s
[11:05:07.884] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:98:0)
[11:05:07.884] Timer tick, asking Buildbucket for the build status
[11:05:08.028] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:08.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:99:0) after 1m0s
[11:06:08.239] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:99:0)
[11:06:08.239] Timer tick, asking Buildbucket for the build status
[11:06:08.608] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:08.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:100:0) after 1m0s
[11:07:08.864] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:100:0)
[11:07:08.864] Timer tick, asking Buildbucket for the build status
[11:07:09.023] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:09.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:101:0) after 1m0s
[11:08:09.166] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:101:0)
[11:08:09.166] Timer tick, asking Buildbucket for the build status
[11:08:09.393] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:09.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:102:0) after 1m0s
[11:09:09.483] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:102:0)
[11:09:09.483] Timer tick, asking Buildbucket for the build status
[11:09:09.742] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:09.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:103:0) after 1m0s
[11:10:09.933] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:103:0)
[11:10:09.933] Timer tick, asking Buildbucket for the build status
[11:10:10.466] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:10.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:104:0) after 1m0s
[11:11:10.673] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:104:0)
[11:11:10.673] Timer tick, asking Buildbucket for the build status
[11:11:10.776] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:10.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:105:0) after 1m0s
[11:12:10.828] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:105:0)
[11:12:10.829] Timer tick, asking Buildbucket for the build status
[11:12:10.942] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:10.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:106:0) after 1m0s
[11:13:10.966] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:106:0)
[11:13:10.966] Timer tick, asking Buildbucket for the build status
[11:13:11.191] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:11.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:107:0) after 1m0s
[11:14:11.300] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:107:0)
[11:14:11.300] Timer tick, asking Buildbucket for the build status
[11:14:11.567] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:11.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:108:0) after 1m0s
[11:15:11.712] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:108:0)
[11:15:11.712] Timer tick, asking Buildbucket for the build status
[11:15:11.982] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:11.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:109:0) after 1m0s
[11:16:12.042] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:109:0)
[11:16:12.042] Timer tick, asking Buildbucket for the build status
[11:16:12.146] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:12.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:110:0) after 1m0s
[11:17:12.205] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:110:0)
[11:17:12.205] Timer tick, asking Buildbucket for the build status
[11:17:13.262] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:13.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:111:0) after 1m0s
[11:18:13.394] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:111:0)
[11:18:13.394] Timer tick, asking Buildbucket for the build status
[11:18:13.595] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:13.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:112:0) after 1m0s
[11:19:13.570] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:112:0)
[11:19:13.570] Timer tick, asking Buildbucket for the build status
[11:19:13.921] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:13.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:113:0) after 1m0s
[11:20:13.989] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:113:0)
[11:20:13.989] Timer tick, asking Buildbucket for the build status
[11:20:14.160] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:14.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:114:0) after 1m0s
[11:21:14.272] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:114:0)
[11:21:14.272] Timer tick, asking Buildbucket for the build status
[11:21:14.393] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:14.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:115:0) after 1m0s
[11:22:14.474] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:115:0)
[11:22:14.474] Timer tick, asking Buildbucket for the build status
[11:22:14.586] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:14.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:116:0) after 1m0s
[11:23:14.806] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:116:0)
[11:23:14.806] Timer tick, asking Buildbucket for the build status
[11:23:14.985] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:14.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:117:0) after 1m0s
[11:24:15.096] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:117:0)
[11:24:15.096] Timer tick, asking Buildbucket for the build status
[11:24:15.327] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:15.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:118:0) after 1m0s
[11:25:15.413] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:118:0)
[11:25:15.413] Timer tick, asking Buildbucket for the build status
[11:25:15.760] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:15.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:119:0) after 1m0s
[11:26:15.885] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:119:0)
[11:26:15.886] Timer tick, asking Buildbucket for the build status
[11:26:15.996] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:15.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:120:0) after 1m0s
[11:27:16.175] Handling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:120:0)
[11:27:16.175] Timer tick, asking Buildbucket for the build status
[11:27:16.716] Build 8888761671300145472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:16.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang:9054191733227738096:121:0) after 1m0s
[11:28:14.707] Received PubSub notification, asking Buildbucket for the build status
[11:28:14.817] Build 8888761671300145472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:28:14.817] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1581420493907070",
"created_by": "project:fuchsia",
"created_ts": "1581413311632319",
"id": "8888761671300145472",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"title\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"title\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"title\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"title\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"title\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"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/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"title\": \"14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/14ecbd7b8ded18af6c95f6a9957da541d1ec0e80\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"title\": \"642bc15dd7186a6317510cf961a8dc3d35e5b713\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/642bc15dd7186a6317510cf961a8dc3d35e5b713\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"title\": \"487621237d2fe5a53ac40a29f6443d3e4f106c61\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/487621237d2fe5a53ac40a29f6443d3e4f106c61\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"title\": \"a7fd548a4f9e50e1aa2def57bae885604c9c5878\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/a7fd548a4f9e50e1aa2def57bae885604c9c5878\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}, \"id\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/refs/heads/master@2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"title\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"url\": \"https://fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-a-0-mniq\", \"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\": \"2733ad2c244ab33553bbd1aaca4c76907fdd0760\", \"timeout\": 21600.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-a-0-mniq\"], \"image\": [\"fuchsia-debian-9-drawfork-v20200123-597b1f5\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.11\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"scale_group\": [\"fuchsia-prod-1\"], \"server_version\": [\"4812-ea7e8fc\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1581413316195991",
"status": "COMPLETED",
"status_changed_ts": "1581420494238351",
"tags": [
"builder:clang",
"buildset:commit/git/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2733ad2c244ab33553bbd1aaca4c76907fdd0760",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054191733227738096",
"scheduler_job_id:fuchsia/clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8888761671300145472/+/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:4a4c65af613ed910",
"user_agent:luci-scheduler"
],
"updated_ts": "1581420494264434",
"url": "https://ci.chromium.org/b/8888761671300145472",
"utcnow_ts": "1581420494804912"
}
}
[11:28:14.817] Invocation finished in 1h59m44.406737891s with status SUCCEEDED