[14:20:46.256] New invocation is queued and will start shortly
[14:20:47.311] Starting the invocation (attempt 1)
[14:20:47.359] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:20:47.361] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:20:47.361] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081805420330062976",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"1e39fc1faa5d8e27877eff45b2b9839c94e5d12e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081805420330062976",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"gitiles_ref:refs/heads/master"
]
}
[14:20:48.069] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555078847455639",
"id": "8916375358409449920",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1e39fc1faa5d8e27877eff45b2b9839c94e5d12e\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555078847892409",
"tags": [
"builder:clang",
"buildset:commit/git/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081805420330062976",
"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/8916375358409449920/+/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": "1555078847892526",
"url": "https://ci.chromium.org/b/8916375358409449920",
"utcnow_ts": "1555078848057819"
}
}
[14:20:48.069] Task URL: https://ci.chromium.org/b/8916375358409449920
[14:20:48.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:2:0) after 1m0s
[14:20:59.313] Received PubSub notification, asking Buildbucket for the build status
[14:20:59.368] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:2:0)
[14:21:48.085] Timer tick, asking Buildbucket for the build status
[14:21:48.183] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:4:0) after 1m0s
[14:22:48.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:4:0)
[14:22:48.200] Timer tick, asking Buildbucket for the build status
[14:22:48.291] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:48.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:5:0) after 1m0s
[14:23:48.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:5:0)
[14:23:48.308] Timer tick, asking Buildbucket for the build status
[14:23:48.371] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:48.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:6:0) after 1m0s
[14:24:48.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:6:0)
[14:24:48.388] Timer tick, asking Buildbucket for the build status
[14:24:48.439] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:48.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:7:0) after 1m0s
[14:25:48.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:7:0)
[14:25:48.466] Timer tick, asking Buildbucket for the build status
[14:25:48.709] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:48.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:8:0) after 1m0s
[14:26:48.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:8:0)
[14:26:48.729] Timer tick, asking Buildbucket for the build status
[14:26:48.853] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:48.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:9:0) after 1m0s
[14:27:48.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:9:0)
[14:27:48.883] Timer tick, asking Buildbucket for the build status
[14:27:48.938] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:48.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:10:0) after 1m0s
[14:28:48.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:10:0)
[14:28:48.970] Timer tick, asking Buildbucket for the build status
[14:28:49.021] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:49.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:11:0) after 1m0s
[14:29:49.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:11:0)
[14:29:49.023] Timer tick, asking Buildbucket for the build status
[14:29:49.255] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:49.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:12:0) after 1m0s
[14:30:49.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:12:0)
[14:30:49.234] Timer tick, asking Buildbucket for the build status
[14:30:49.384] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:49.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:13:0) after 1m0s
[14:31:49.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:13:0)
[14:31:49.401] Timer tick, asking Buildbucket for the build status
[14:31:49.445] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:49.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:14:0) after 1m0s
[14:32:49.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:14:0)
[14:32:49.464] Timer tick, asking Buildbucket for the build status
[14:32:49.585] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:49.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:15:0) after 1m0s
[14:33:49.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:15:0)
[14:33:49.606] Timer tick, asking Buildbucket for the build status
[14:33:49.679] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:49.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:16:0) after 1m0s
[14:34:49.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:16:0)
[14:34:49.690] Timer tick, asking Buildbucket for the build status
[14:34:49.808] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:49.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:17:0) after 1m0s
[14:35:49.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:17:0)
[14:35:49.828] Timer tick, asking Buildbucket for the build status
[14:35:49.888] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:49.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:18:0) after 1m0s
[14:36:49.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:18:0)
[14:36:49.907] Timer tick, asking Buildbucket for the build status
[14:36:49.946] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:49.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:19:0) after 1m0s
[14:37:49.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:19:0)
[14:37:49.972] Timer tick, asking Buildbucket for the build status
[14:37:50.013] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:50.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:20:0) after 1m0s
[14:38:50.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:20:0)
[14:38:50.032] Timer tick, asking Buildbucket for the build status
[14:38:50.234] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:50.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:21:0) after 1m0s
[14:39:50.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:21:0)
[14:39:50.268] Timer tick, asking Buildbucket for the build status
[14:39:50.326] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:50.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:22:0) after 1m0s
[14:40:50.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:22:0)
[14:40:50.363] Timer tick, asking Buildbucket for the build status
[14:40:50.435] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:50.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:23:0) after 1m0s
[14:41:50.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:23:0)
[14:41:50.460] Timer tick, asking Buildbucket for the build status
[14:41:50.512] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:50.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:24:0) after 1m0s
[14:42:50.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:24:0)
[14:42:50.536] Timer tick, asking Buildbucket for the build status
[14:42:50.603] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:50.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:25:0) after 1m0s
[14:43:50.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:25:0)
[14:43:50.621] Timer tick, asking Buildbucket for the build status
[14:43:50.664] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:50.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:26:0) after 1m0s
[14:44:50.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:26:0)
[14:44:50.690] Timer tick, asking Buildbucket for the build status
[14:44:50.850] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:50.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:27:0) after 1m0s
[14:45:50.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:27:0)
[14:45:50.879] Timer tick, asking Buildbucket for the build status
[14:45:50.997] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:50.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:28:0) after 1m0s
[14:46:50.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:28:0)
[14:46:50.995] Timer tick, asking Buildbucket for the build status
[14:46:51.102] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:51.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:29:0) after 1m0s
[14:47:51.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:29:0)
[14:47:51.139] Timer tick, asking Buildbucket for the build status
[14:47:51.196] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:51.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:30:0) after 1m0s
[14:48:51.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:30:0)
[14:48:51.183] Timer tick, asking Buildbucket for the build status
[14:48:51.291] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:51.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:31:0) after 1m0s
[14:49:51.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:31:0)
[14:49:51.305] Timer tick, asking Buildbucket for the build status
[14:49:51.363] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:51.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:32:0) after 1m0s
[14:50:51.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:32:0)
[14:50:51.383] Timer tick, asking Buildbucket for the build status
[14:50:51.482] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:51.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:33:0) after 1m0s
[14:51:51.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:33:0)
[14:51:51.506] Timer tick, asking Buildbucket for the build status
[14:51:51.594] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:51.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:34:0) after 1m0s
[14:52:51.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:34:0)
[14:52:51.616] Timer tick, asking Buildbucket for the build status
[14:52:51.744] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:51.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:35:0) after 1m0s
[14:53:51.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:35:0)
[14:53:51.741] Timer tick, asking Buildbucket for the build status
[14:53:51.789] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:51.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:36:0) after 1m0s
[14:54:51.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:36:0)
[14:54:51.806] Timer tick, asking Buildbucket for the build status
[14:54:51.855] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:51.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:37:0) after 1m0s
[14:55:51.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:37:0)
[14:55:51.859] Timer tick, asking Buildbucket for the build status
[14:55:51.925] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:51.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:38:0) after 1m0s
[14:56:51.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:38:0)
[14:56:51.954] Timer tick, asking Buildbucket for the build status
[14:56:52.087] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:52.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:39:0) after 1m0s
[14:57:52.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:39:0)
[14:57:52.115] Timer tick, asking Buildbucket for the build status
[14:57:52.191] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:52.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:40:0) after 1m0s
[14:58:52.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:40:0)
[14:58:52.212] Timer tick, asking Buildbucket for the build status
[14:58:52.307] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:52.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:41:0) after 1m0s
[14:59:52.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:41:0)
[14:59:52.286] Timer tick, asking Buildbucket for the build status
[14:59:52.361] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:52.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:42:0) after 1m0s
[15:00:52.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:42:0)
[15:00:52.375] Timer tick, asking Buildbucket for the build status
[15:00:52.451] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:52.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:43:0) after 1m0s
[15:01:52.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:43:0)
[15:01:52.438] Timer tick, asking Buildbucket for the build status
[15:01:52.526] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:52.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:44:0) after 1m0s
[15:02:52.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:44:0)
[15:02:52.548] Timer tick, asking Buildbucket for the build status
[15:02:52.670] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:52.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:45:0) after 1m0s
[15:03:52.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:45:0)
[15:03:52.691] Timer tick, asking Buildbucket for the build status
[15:03:53.543] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:53.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:46:0) after 1m0s
[15:04:53.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:46:0)
[15:04:53.525] Timer tick, asking Buildbucket for the build status
[15:04:53.578] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:53.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:47:0) after 1m0s
[15:05:53.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:47:0)
[15:05:53.608] Timer tick, asking Buildbucket for the build status
[15:05:56.873] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:56.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:48:0) after 1m0s
[15:06:56.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:48:0)
[15:06:56.877] Timer tick, asking Buildbucket for the build status
[15:06:56.970] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:56.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:49:0) after 1m0s
[15:07:57.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:49:0)
[15:07:57.000] Timer tick, asking Buildbucket for the build status
[15:07:57.117] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:57.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:50:0) after 1m0s
[15:08:57.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:50:0)
[15:08:57.092] Timer tick, asking Buildbucket for the build status
[15:08:57.202] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:57.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:51:0) after 1m0s
[15:09:57.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:51:0)
[15:09:57.231] Timer tick, asking Buildbucket for the build status
[15:09:57.378] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:57.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:52:0) after 1m0s
[15:10:57.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:52:0)
[15:10:57.367] Timer tick, asking Buildbucket for the build status
[15:10:57.680] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:57.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:53:0) after 1m0s
[15:11:57.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:53:0)
[15:11:57.654] Timer tick, asking Buildbucket for the build status
[15:11:57.731] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:57.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:54:0) after 1m0s
[15:12:57.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:54:0)
[15:12:57.771] Timer tick, asking Buildbucket for the build status
[15:12:57.894] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:57.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:55:0) after 1m0s
[15:13:57.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:55:0)
[15:13:57.904] Timer tick, asking Buildbucket for the build status
[15:13:58.096] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:58.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:56:0) after 1m0s
[15:14:58.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:56:0)
[15:14:58.117] Timer tick, asking Buildbucket for the build status
[15:14:58.259] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:58.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:57:0) after 1m0s
[15:15:58.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:57:0)
[15:15:58.247] Timer tick, asking Buildbucket for the build status
[15:15:58.338] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:58.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:58:0) after 1m0s
[15:16:58.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:58:0)
[15:16:58.324] Timer tick, asking Buildbucket for the build status
[15:16:58.592] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:58.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:59:0) after 1m0s
[15:17:58.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:59:0)
[15:17:58.618] Timer tick, asking Buildbucket for the build status
[15:17:58.716] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:58.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:60:0) after 1m0s
[15:18:58.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:60:0)
[15:18:58.719] Timer tick, asking Buildbucket for the build status
[15:18:58.800] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:58.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:61:0) after 1m0s
[15:19:58.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:61:0)
[15:19:58.821] Timer tick, asking Buildbucket for the build status
[15:19:58.902] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:58.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:62:0) after 1m0s
[15:20:58.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:62:0)
[15:20:58.922] Timer tick, asking Buildbucket for the build status
[15:20:59.050] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:59.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:63:0) after 1m0s
[15:21:59.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:63:0)
[15:21:59.074] Timer tick, asking Buildbucket for the build status
[15:21:59.272] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:59.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:64:0) after 1m0s
[15:22:59.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:64:0)
[15:22:59.294] Timer tick, asking Buildbucket for the build status
[15:22:59.363] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:59.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:65:0) after 1m0s
[15:23:59.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:65:0)
[15:23:59.385] Timer tick, asking Buildbucket for the build status
[15:23:59.594] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:59.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:66:0) after 1m0s
[15:24:59.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:66:0)
[15:24:59.583] Timer tick, asking Buildbucket for the build status
[15:24:59.695] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:59.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:67:0) after 1m0s
[15:25:59.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:67:0)
[15:25:59.715] Timer tick, asking Buildbucket for the build status
[15:25:59.896] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:59.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:68:0) after 1m0s
[15:26:59.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:68:0)
[15:26:59.881] Timer tick, asking Buildbucket for the build status
[15:27:00.087] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:00.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:69:0) after 1m0s
[15:28:00.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:69:0)
[15:28:00.112] Timer tick, asking Buildbucket for the build status
[15:28:00.166] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:00.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:70:0) after 1m0s
[15:29:00.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:70:0)
[15:29:00.190] Timer tick, asking Buildbucket for the build status
[15:29:00.396] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:00.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:71:0) after 1m0s
[15:30:00.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:71:0)
[15:30:00.395] Timer tick, asking Buildbucket for the build status
[15:30:00.453] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:00.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:72:0) after 1m0s
[15:31:00.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:72:0)
[15:31:00.475] Timer tick, asking Buildbucket for the build status
[15:31:00.664] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:00.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:73:0) after 1m0s
[15:32:00.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:73:0)
[15:32:00.649] Timer tick, asking Buildbucket for the build status
[15:32:00.862] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:00.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:74:0) after 1m0s
[15:33:00.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:74:0)
[15:33:00.840] Timer tick, asking Buildbucket for the build status
[15:33:00.967] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:00.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:75:0) after 1m0s
[15:34:00.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:75:0)
[15:34:00.979] Timer tick, asking Buildbucket for the build status
[15:34:01.015] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:01.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:76:0) after 1m0s
[15:35:01.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:76:0)
[15:35:01.036] Timer tick, asking Buildbucket for the build status
[15:35:01.117] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:01.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:77:0) after 1m0s
[15:36:01.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:77:0)
[15:36:01.095] Timer tick, asking Buildbucket for the build status
[15:36:01.144] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:01.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:78:0) after 1m0s
[15:37:01.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:78:0)
[15:37:01.119] Timer tick, asking Buildbucket for the build status
[15:37:01.163] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:01.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:79:0) after 1m0s
[15:38:01.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:79:0)
[15:38:01.185] Timer tick, asking Buildbucket for the build status
[15:38:01.477] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:01.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:80:0) after 1m0s
[15:39:01.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:80:0)
[15:39:01.469] Timer tick, asking Buildbucket for the build status
[15:39:01.573] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:01.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:81:0) after 1m0s
[15:40:01.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:81:0)
[15:40:01.570] Timer tick, asking Buildbucket for the build status
[15:40:01.917] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:01.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:82:0) after 1m0s
[15:41:01.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:82:0)
[15:41:01.911] Timer tick, asking Buildbucket for the build status
[15:41:02.070] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:02.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:83:0) after 1m0s
[15:42:02.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:83:0)
[15:42:02.085] Timer tick, asking Buildbucket for the build status
[15:42:02.146] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:02.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:84:0) after 1m0s
[15:43:02.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:84:0)
[15:43:02.197] Timer tick, asking Buildbucket for the build status
[15:43:02.281] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:02.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:85:0) after 1m0s
[15:44:02.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:85:0)
[15:44:02.279] Timer tick, asking Buildbucket for the build status
[15:44:02.475] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:02.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:86:0) after 1m0s
[15:45:02.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:86:0)
[15:45:02.513] Timer tick, asking Buildbucket for the build status
[15:45:02.723] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:02.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:87:0) after 1m0s
[15:46:02.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:87:0)
[15:46:02.751] Timer tick, asking Buildbucket for the build status
[15:46:02.796] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:02.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:88:0) after 1m0s
[15:47:02.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:88:0)
[15:47:02.817] Timer tick, asking Buildbucket for the build status
[15:47:02.892] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:02.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:89:0) after 1m0s
[15:48:02.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:89:0)
[15:48:02.892] Timer tick, asking Buildbucket for the build status
[15:48:02.963] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:02.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:90:0) after 1m0s
[15:49:02.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:90:0)
[15:49:02.989] Timer tick, asking Buildbucket for the build status
[15:49:03.226] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:03.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:91:0) after 1m0s
[15:50:03.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:91:0)
[15:50:03.246] Timer tick, asking Buildbucket for the build status
[15:50:03.304] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:03.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:92:0) after 1m0s
[15:51:03.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:92:0)
[15:51:03.278] Timer tick, asking Buildbucket for the build status
[15:51:03.335] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:03.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:93:0) after 1m0s
[15:52:03.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:93:0)
[15:52:03.346] Timer tick, asking Buildbucket for the build status
[15:52:03.420] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:03.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:94:0) after 1m0s
[15:53:03.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:94:0)
[15:53:03.463] Timer tick, asking Buildbucket for the build status
[15:53:03.611] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:03.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:95:0) after 1m0s
[15:54:03.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:95:0)
[15:54:03.710] Timer tick, asking Buildbucket for the build status
[15:54:04.788] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:04.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:96:0) after 1m0s
[15:55:04.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:96:0)
[15:55:04.805] Timer tick, asking Buildbucket for the build status
[15:55:05.021] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:05.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:97:0) after 1m0s
[15:56:05.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:97:0)
[15:56:05.105] Timer tick, asking Buildbucket for the build status
[15:56:05.165] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:05.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:98:0) after 1m0s
[15:57:05.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:98:0)
[15:57:05.192] Timer tick, asking Buildbucket for the build status
[15:57:05.354] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:05.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:99:0) after 1m0s
[15:58:05.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:99:0)
[15:58:05.410] Timer tick, asking Buildbucket for the build status
[15:58:05.491] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:05.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:100:0) after 1m0s
[15:59:05.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:100:0)
[15:59:05.514] Timer tick, asking Buildbucket for the build status
[15:59:05.588] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:05.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:101:0) after 1m0s
[16:00:05.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:101:0)
[16:00:05.609] Timer tick, asking Buildbucket for the build status
[16:00:05.656] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:05.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:102:0) after 1m0s
[16:01:05.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:102:0)
[16:01:05.718] Timer tick, asking Buildbucket for the build status
[16:01:05.843] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:05.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:103:0) after 1m0s
[16:02:05.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:103:0)
[16:02:05.831] Timer tick, asking Buildbucket for the build status
[16:02:05.870] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:05.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:104:0) after 1m0s
[16:03:05.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:104:0)
[16:03:05.898] Timer tick, asking Buildbucket for the build status
[16:03:05.969] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:05.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:105:0) after 1m0s
[16:04:06.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:105:0)
[16:04:06.052] Timer tick, asking Buildbucket for the build status
[16:04:06.127] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:06.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:106:0) after 1m0s
[16:05:06.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:106:0)
[16:05:06.114] Timer tick, asking Buildbucket for the build status
[16:05:06.168] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:06.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:107:0) after 1m0s
[16:06:06.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:107:0)
[16:06:06.164] Timer tick, asking Buildbucket for the build status
[16:06:06.376] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:06.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:108:0) after 1m0s
[16:07:06.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:108:0)
[16:07:06.416] Timer tick, asking Buildbucket for the build status
[16:07:06.487] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:06.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:109:0) after 1m0s
[16:08:06.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:109:0)
[16:08:06.526] Timer tick, asking Buildbucket for the build status
[16:08:06.635] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:06.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:110:0) after 1m0s
[16:09:06.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:110:0)
[16:09:06.726] Timer tick, asking Buildbucket for the build status
[16:09:06.821] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:06.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:111:0) after 1m0s
[16:10:06.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:111:0)
[16:10:06.822] Timer tick, asking Buildbucket for the build status
[16:10:06.913] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:06.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:112:0) after 1m0s
[16:11:06.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:112:0)
[16:11:06.925] Timer tick, asking Buildbucket for the build status
[16:11:07.117] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:07.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:113:0) after 1m0s
[16:12:07.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:113:0)
[16:12:07.133] Timer tick, asking Buildbucket for the build status
[16:12:07.171] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:07.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:114:0) after 1m0s
[16:13:07.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:114:0)
[16:13:07.227] Timer tick, asking Buildbucket for the build status
[16:13:07.324] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:07.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:115:0) after 1m0s
[16:14:07.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:115:0)
[16:14:07.329] Timer tick, asking Buildbucket for the build status
[16:14:07.381] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:07.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:116:0) after 1m0s
[16:15:07.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:116:0)
[16:15:07.368] Timer tick, asking Buildbucket for the build status
[16:15:07.491] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:07.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:117:0) after 1m0s
[16:16:07.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:117:0)
[16:16:07.506] Timer tick, asking Buildbucket for the build status
[16:16:07.551] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:07.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:118:0) after 1m0s
[16:17:07.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:118:0)
[16:17:07.570] Timer tick, asking Buildbucket for the build status
[16:17:07.619] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:07.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:119:0) after 1m0s
[16:18:07.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:119:0)
[16:18:07.651] Timer tick, asking Buildbucket for the build status
[16:18:07.710] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:07.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:120:0) after 1m0s
[16:19:07.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:120:0)
[16:19:07.730] Timer tick, asking Buildbucket for the build status
[16:19:07.803] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:07.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:121:0) after 1m0s
[16:20:07.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:121:0)
[16:20:07.828] Timer tick, asking Buildbucket for the build status
[16:20:07.869] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:07.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:122:0) after 1m0s
[16:21:07.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:122:0)
[16:21:07.860] Timer tick, asking Buildbucket for the build status
[16:21:07.926] Build 8916375358409449920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:07.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081805420330062976:123:0) after 1m0s
[16:21:21.320] Received PubSub notification, asking Buildbucket for the build status
[16:21:21.443] Build 8916375358409449920: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:21:21.443] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555086080891136",
"created_by": "project:fuchsia",
"created_ts": "1555078847455639",
"failure_reason": "BUILD_FAILURE",
"id": "8916375358409449920",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1e39fc1faa5d8e27877eff45b2b9839c94e5d12e\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-hml5\", \"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\": \"1e39fc1faa5d8e27877eff45b2b9839c94e5d12e\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-hml5\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4253-3d44409\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555078858521135",
"status": "COMPLETED",
"status_changed_ts": "1555078858891056",
"tags": [
"builder:clang",
"buildset:commit/git/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1e39fc1faa5d8e27877eff45b2b9839c94e5d12e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081805420330062976",
"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/8916375358409449920/+/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:442abda7db5b7710",
"user_agent:luci-scheduler"
],
"updated_ts": "1555086081064777",
"url": "https://ci.chromium.org/b/8916375358409449920",
"utcnow_ts": "1555086081432941"
}
}
[16:21:21.444] Invocation finished in 2h0m35.203748978s with status FAILED