[11:41:56.078] New invocation is queued and will start shortly
[11:41:57.223] Starting the invocation (attempt 1)
[11:41:57.347] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:41:57.347] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:41:57.347] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083989740638943616",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b274d3d79930034b8c3b2e0fc2372b66ef171eb1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083989740638943616",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"gitiles_ref:refs/heads/master"
]
}
[11:41:58.394] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1552995717422096",
"id": "8918559678567101328",
"lease_expiration_ts": "1553121718034835",
"lease_key": "1402228039",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b274d3d79930034b8c3b2e0fc2372b66ef171eb1\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552995718173618",
"tags": [
"builder:clang",
"buildset:commit/git/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083989740638943616",
"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/8918559678567101328/+/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:43ae939aacf71a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552995718173738",
"url": "https://ci.chromium.org/b/8918559678567101328",
"utcnow_ts": "1552995718378393"
}
}
[11:41:58.394] Task URL: https://ci.chromium.org/b/8918559678567101328
[11:41:58.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:2:0) after 1m0s
[11:42:18.095] Received PubSub notification, asking Buildbucket for the build status
[11:42:18.151] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:58.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:2:0)
[11:42:58.379] Timer tick, asking Buildbucket for the build status
[11:42:58.459] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:58.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:4:0) after 1m0s
[11:43:58.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:4:0)
[11:43:58.468] Timer tick, asking Buildbucket for the build status
[11:43:58.592] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:58.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:5:0) after 1m0s
[11:44:58.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:5:0)
[11:44:58.588] Timer tick, asking Buildbucket for the build status
[11:44:58.696] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:58.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:6:0) after 1m0s
[11:45:58.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:6:0)
[11:45:58.739] Timer tick, asking Buildbucket for the build status
[11:45:58.800] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:58.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:7:0) after 1m0s
[11:46:58.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:7:0)
[11:46:58.796] Timer tick, asking Buildbucket for the build status
[11:46:58.886] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:58.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:8:0) after 1m0s
[11:47:58.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:8:0)
[11:47:58.904] Timer tick, asking Buildbucket for the build status
[11:47:59.022] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:59.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:9:0) after 1m0s
[11:48:59.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:9:0)
[11:48:59.042] Timer tick, asking Buildbucket for the build status
[11:48:59.267] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:59.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:10:0) after 1m0s
[11:49:59.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:10:0)
[11:49:59.292] Timer tick, asking Buildbucket for the build status
[11:49:59.406] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:59.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:11:0) after 1m0s
[11:50:59.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:11:0)
[11:50:59.438] Timer tick, asking Buildbucket for the build status
[11:50:59.605] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:59.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:12:0) after 1m0s
[11:51:59.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:12:0)
[11:51:59.608] Timer tick, asking Buildbucket for the build status
[11:51:59.649] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:59.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:13:0) after 1m0s
[11:52:59.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:13:0)
[11:52:59.667] Timer tick, asking Buildbucket for the build status
[11:52:59.766] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:59.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:14:0) after 1m0s
[11:53:59.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:14:0)
[11:53:59.748] Timer tick, asking Buildbucket for the build status
[11:53:59.809] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:59.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:15:0) after 1m0s
[11:54:59.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:15:0)
[11:54:59.801] Timer tick, asking Buildbucket for the build status
[11:54:59.869] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:59.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:16:0) after 1m0s
[11:55:59.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:16:0)
[11:55:59.875] Timer tick, asking Buildbucket for the build status
[11:55:59.932] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:59.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:17:0) after 1m0s
[11:56:59.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:17:0)
[11:56:59.950] Timer tick, asking Buildbucket for the build status
[11:57:00.020] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:00.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:18:0) after 1m0s
[11:58:00.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:18:0)
[11:58:00.048] Timer tick, asking Buildbucket for the build status
[11:58:00.126] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:00.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:19:0) after 1m0s
[11:59:00.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:19:0)
[11:59:00.145] Timer tick, asking Buildbucket for the build status
[11:59:00.257] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:00.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:20:0) after 1m0s
[12:00:00.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:20:0)
[12:00:00.270] Timer tick, asking Buildbucket for the build status
[12:00:00.578] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:00.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:21:0) after 1m0s
[12:01:00.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:21:0)
[12:01:00.569] Timer tick, asking Buildbucket for the build status
[12:01:00.680] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:00.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:22:0) after 1m0s
[12:02:00.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:22:0)
[12:02:00.697] Timer tick, asking Buildbucket for the build status
[12:02:00.930] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:00.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:23:0) after 1m0s
[12:03:00.952] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:23:0)
[12:03:00.952] Timer tick, asking Buildbucket for the build status
[12:03:01.199] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:01.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:24:0) after 1m0s
[12:04:01.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:24:0)
[12:04:01.223] Timer tick, asking Buildbucket for the build status
[12:04:01.389] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:01.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:25:0) after 1m0s
[12:05:01.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:25:0)
[12:05:01.390] Timer tick, asking Buildbucket for the build status
[12:05:01.549] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:01.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:26:0) after 1m0s
[12:06:01.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:26:0)
[12:06:01.552] Timer tick, asking Buildbucket for the build status
[12:06:01.621] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:01.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:27:0) after 1m0s
[12:07:01.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:27:0)
[12:07:01.644] Timer tick, asking Buildbucket for the build status
[12:07:01.697] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:01.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:28:0) after 1m0s
[12:08:01.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:28:0)
[12:08:01.675] Timer tick, asking Buildbucket for the build status
[12:08:01.799] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:01.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:29:0) after 1m0s
[12:09:01.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:29:0)
[12:09:01.818] Timer tick, asking Buildbucket for the build status
[12:09:02.065] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:02.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:30:0) after 1m0s
[12:10:02.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:30:0)
[12:10:02.087] Timer tick, asking Buildbucket for the build status
[12:10:02.152] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:02.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:31:0) after 1m0s
[12:11:02.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:31:0)
[12:11:02.177] Timer tick, asking Buildbucket for the build status
[12:11:02.481] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:02.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:32:0) after 1m0s
[12:12:02.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:32:0)
[12:12:02.477] Timer tick, asking Buildbucket for the build status
[12:12:02.592] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:02.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:33:0) after 1m0s
[12:13:02.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:33:0)
[12:13:02.603] Timer tick, asking Buildbucket for the build status
[12:13:04.144] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:04.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:34:0) after 1m0s
[12:14:04.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:34:0)
[12:14:04.124] Timer tick, asking Buildbucket for the build status
[12:14:04.243] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:04.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:35:0) after 1m0s
[12:15:04.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:35:0)
[12:15:04.261] Timer tick, asking Buildbucket for the build status
[12:15:04.574] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:04.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:36:0) after 1m0s
[12:16:04.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:36:0)
[12:16:04.572] Timer tick, asking Buildbucket for the build status
[12:16:04.705] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:04.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:37:0) after 1m0s
[12:17:04.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:37:0)
[12:17:04.708] Timer tick, asking Buildbucket for the build status
[12:17:04.769] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:04.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:38:0) after 1m0s
[12:18:04.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:38:0)
[12:18:04.789] Timer tick, asking Buildbucket for the build status
[12:18:04.861] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:04.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:39:0) after 1m0s
[12:19:04.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:39:0)
[12:19:04.879] Timer tick, asking Buildbucket for the build status
[12:19:05.068] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:05.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:40:0) after 1m0s
[12:20:05.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:40:0)
[12:20:05.049] Timer tick, asking Buildbucket for the build status
[12:20:05.111] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:05.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:41:0) after 1m0s
[12:21:05.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:41:0)
[12:21:05.147] Timer tick, asking Buildbucket for the build status
[12:21:05.189] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:05.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:42:0) after 1m0s
[12:22:05.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:42:0)
[12:22:05.163] Timer tick, asking Buildbucket for the build status
[12:22:05.225] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:05.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:43:0) after 1m0s
[12:23:05.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:43:0)
[12:23:05.268] Timer tick, asking Buildbucket for the build status
[12:23:05.350] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:05.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:44:0) after 1m0s
[12:24:05.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:44:0)
[12:24:05.369] Timer tick, asking Buildbucket for the build status
[12:24:05.490] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:05.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:45:0) after 1m0s
[12:25:05.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:45:0)
[12:25:05.507] Timer tick, asking Buildbucket for the build status
[12:25:05.563] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:05.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:46:0) after 1m0s
[12:26:05.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:46:0)
[12:26:05.592] Timer tick, asking Buildbucket for the build status
[12:26:05.636] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:05.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:47:0) after 1m0s
[12:27:05.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:47:0)
[12:27:05.657] Timer tick, asking Buildbucket for the build status
[12:27:05.792] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:05.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:48:0) after 1m0s
[12:28:05.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:48:0)
[12:28:05.811] Timer tick, asking Buildbucket for the build status
[12:28:05.859] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:05.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:49:0) after 1m0s
[12:29:05.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:49:0)
[12:29:05.859] Timer tick, asking Buildbucket for the build status
[12:29:05.904] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:05.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:50:0) after 1m0s
[12:30:05.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:50:0)
[12:30:05.925] Timer tick, asking Buildbucket for the build status
[12:30:05.994] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:05.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:51:0) after 1m0s
[12:31:05.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:51:0)
[12:31:05.981] Timer tick, asking Buildbucket for the build status
[12:31:06.067] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:06.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:52:0) after 1m0s
[12:32:06.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:52:0)
[12:32:06.069] Timer tick, asking Buildbucket for the build status
[12:32:06.228] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:06.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:53:0) after 1m0s
[12:33:06.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:53:0)
[12:33:06.251] Timer tick, asking Buildbucket for the build status
[12:33:06.486] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:06.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:54:0) after 1m0s
[12:34:06.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:54:0)
[12:34:06.507] Timer tick, asking Buildbucket for the build status
[12:34:06.559] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:06.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:55:0) after 1m0s
[12:35:06.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:55:0)
[12:35:06.580] Timer tick, asking Buildbucket for the build status
[12:35:06.650] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:06.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:56:0) after 1m0s
[12:36:06.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:56:0)
[12:36:06.670] Timer tick, asking Buildbucket for the build status
[12:36:06.962] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:06.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:57:0) after 1m0s
[12:37:06.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:57:0)
[12:37:06.982] Timer tick, asking Buildbucket for the build status
[12:37:07.043] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:07.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:58:0) after 1m0s
[12:38:07.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:58:0)
[12:38:07.069] Timer tick, asking Buildbucket for the build status
[12:38:07.197] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:07.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:59:0) after 1m0s
[12:39:07.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:59:0)
[12:39:07.196] Timer tick, asking Buildbucket for the build status
[12:39:07.275] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:07.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:60:0) after 1m0s
[12:40:07.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:60:0)
[12:40:07.295] Timer tick, asking Buildbucket for the build status
[12:40:07.390] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:07.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:61:0) after 1m0s
[12:41:07.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:61:0)
[12:41:07.452] Timer tick, asking Buildbucket for the build status
[12:41:07.598] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:07.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:62:0) after 1m0s
[12:42:07.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:62:0)
[12:42:07.617] Timer tick, asking Buildbucket for the build status
[12:42:07.724] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:07.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:63:0) after 1m0s
[12:43:07.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:63:0)
[12:43:07.730] Timer tick, asking Buildbucket for the build status
[12:43:07.801] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:07.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:64:0) after 1m0s
[12:44:07.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:64:0)
[12:44:07.775] Timer tick, asking Buildbucket for the build status
[12:44:07.904] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:07.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:65:0) after 1m0s
[12:45:07.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:65:0)
[12:45:07.922] Timer tick, asking Buildbucket for the build status
[12:45:07.968] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:07.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:66:0) after 1m0s
[12:46:07.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:66:0)
[12:46:07.988] Timer tick, asking Buildbucket for the build status
[12:46:08.111] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:08.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:67:0) after 1m0s
[12:47:08.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:67:0)
[12:47:08.101] Timer tick, asking Buildbucket for the build status
[12:47:08.195] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:08.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:68:0) after 1m0s
[12:48:08.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:68:0)
[12:48:08.212] Timer tick, asking Buildbucket for the build status
[12:48:08.312] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:08.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:69:0) after 1m0s
[12:49:08.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:69:0)
[12:49:08.333] Timer tick, asking Buildbucket for the build status
[12:49:08.418] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:08.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:70:0) after 1m0s
[12:50:08.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:70:0)
[12:50:08.438] Timer tick, asking Buildbucket for the build status
[12:50:08.582] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:08.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:71:0) after 1m0s
[12:51:08.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:71:0)
[12:51:08.583] Timer tick, asking Buildbucket for the build status
[12:51:08.753] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:08.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:72:0) after 1m0s
[12:52:08.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:72:0)
[12:52:08.774] Timer tick, asking Buildbucket for the build status
[12:52:08.835] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:08.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:73:0) after 1m0s
[12:53:08.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:73:0)
[12:53:08.857] Timer tick, asking Buildbucket for the build status
[12:53:08.960] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:08.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:74:0) after 1m0s
[12:54:08.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:74:0)
[12:54:08.983] Timer tick, asking Buildbucket for the build status
[12:54:09.159] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:09.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:75:0) after 1m0s
[12:55:09.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:75:0)
[12:55:09.169] Timer tick, asking Buildbucket for the build status
[12:55:09.223] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:09.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:76:0) after 1m0s
[12:56:09.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:76:0)
[12:56:09.206] Timer tick, asking Buildbucket for the build status
[12:56:09.329] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:09.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:77:0) after 1m0s
[12:57:09.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:77:0)
[12:57:09.369] Timer tick, asking Buildbucket for the build status
[12:57:09.465] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:09.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:78:0) after 1m0s
[12:58:09.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:78:0)
[12:58:09.481] Timer tick, asking Buildbucket for the build status
[12:58:09.534] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:09.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:79:0) after 1m0s
[12:59:09.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:79:0)
[12:59:09.559] Timer tick, asking Buildbucket for the build status
[12:59:10.081] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:10.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:80:0) after 1m0s
[13:00:10.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:80:0)
[13:00:10.122] Timer tick, asking Buildbucket for the build status
[13:00:10.293] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:10.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:81:0) after 1m0s
[13:01:10.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:81:0)
[13:01:10.276] Timer tick, asking Buildbucket for the build status
[13:01:10.575] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:10.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:82:0) after 1m0s
[13:02:10.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:82:0)
[13:02:10.598] Timer tick, asking Buildbucket for the build status
[13:02:10.645] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:10.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:83:0) after 1m0s
[13:03:10.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:83:0)
[13:03:10.622] Timer tick, asking Buildbucket for the build status
[13:03:10.695] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:10.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:84:0) after 1m0s
[13:04:10.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:84:0)
[13:04:10.715] Timer tick, asking Buildbucket for the build status
[13:04:10.865] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:10.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:85:0) after 1m0s
[13:05:10.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:85:0)
[13:05:10.888] Timer tick, asking Buildbucket for the build status
[13:05:10.977] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:10.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:86:0) after 1m0s
[13:06:10.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:86:0)
[13:06:10.985] Timer tick, asking Buildbucket for the build status
[13:06:11.271] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:11.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:87:0) after 1m0s
[13:07:11.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:87:0)
[13:07:11.289] Timer tick, asking Buildbucket for the build status
[13:07:11.390] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:11.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:88:0) after 1m0s
[13:08:11.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:88:0)
[13:08:11.410] Timer tick, asking Buildbucket for the build status
[13:08:11.453] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:11.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:89:0) after 1m0s
[13:09:11.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:89:0)
[13:09:11.457] Timer tick, asking Buildbucket for the build status
[13:09:11.517] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:11.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:90:0) after 1m0s
[13:10:11.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:90:0)
[13:10:11.514] Timer tick, asking Buildbucket for the build status
[13:10:11.592] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:11.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:91:0) after 1m0s
[13:11:11.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:91:0)
[13:11:11.597] Timer tick, asking Buildbucket for the build status
[13:11:11.647] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:11.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:92:0) after 1m0s
[13:12:11.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:92:0)
[13:12:11.665] Timer tick, asking Buildbucket for the build status
[13:12:11.724] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:11.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:93:0) after 1m0s
[13:13:11.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:93:0)
[13:13:11.750] Timer tick, asking Buildbucket for the build status
[13:13:11.794] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:11.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:94:0) after 1m0s
[13:14:11.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:94:0)
[13:14:11.779] Timer tick, asking Buildbucket for the build status
[13:14:11.822] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:11.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:95:0) after 1m0s
[13:15:11.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:95:0)
[13:15:11.884] Timer tick, asking Buildbucket for the build status
[13:15:11.941] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:11.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:96:0) after 1m0s
[13:16:11.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:96:0)
[13:16:11.930] Timer tick, asking Buildbucket for the build status
[13:16:12.010] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:12.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:97:0) after 1m0s
[13:17:12.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:97:0)
[13:17:12.112] Timer tick, asking Buildbucket for the build status
[13:17:12.157] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:12.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:98:0) after 1m0s
[13:18:12.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:98:0)
[13:18:12.181] Timer tick, asking Buildbucket for the build status
[13:18:12.357] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:12.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:99:0) after 1m0s
[13:19:12.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:99:0)
[13:19:12.379] Timer tick, asking Buildbucket for the build status
[13:19:12.490] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:12.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:100:0) after 1m0s
[13:20:12.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:100:0)
[13:20:12.512] Timer tick, asking Buildbucket for the build status
[13:20:12.592] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:12.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:101:0) after 1m0s
[13:21:12.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:101:0)
[13:21:12.618] Timer tick, asking Buildbucket for the build status
[13:21:12.660] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:12.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:102:0) after 1m0s
[13:22:12.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:102:0)
[13:22:12.653] Timer tick, asking Buildbucket for the build status
[13:22:12.710] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:12.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:103:0) after 1m0s
[13:23:12.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:103:0)
[13:23:12.737] Timer tick, asking Buildbucket for the build status
[13:23:12.796] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:12.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:104:0) after 1m0s
[13:24:12.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:104:0)
[13:24:12.820] Timer tick, asking Buildbucket for the build status
[13:24:12.870] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:12.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:105:0) after 1m0s
[13:25:12.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:105:0)
[13:25:12.883] Timer tick, asking Buildbucket for the build status
[13:25:13.054] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:13.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:106:0) after 1m0s
[13:26:13.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:106:0)
[13:26:13.079] Timer tick, asking Buildbucket for the build status
[13:26:13.125] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:13.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:107:0) after 1m0s
[13:27:13.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:107:0)
[13:27:13.145] Timer tick, asking Buildbucket for the build status
[13:27:13.287] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:13.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:108:0) after 1m0s
[13:28:13.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:108:0)
[13:28:13.271] Timer tick, asking Buildbucket for the build status
[13:28:13.326] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:13.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:109:0) after 1m0s
[13:29:13.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:109:0)
[13:29:13.347] Timer tick, asking Buildbucket for the build status
[13:29:13.404] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:13.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:110:0) after 1m0s
[13:30:13.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:110:0)
[13:30:13.392] Timer tick, asking Buildbucket for the build status
[13:30:13.434] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:13.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:111:0) after 1m0s
[13:31:13.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:111:0)
[13:31:13.448] Timer tick, asking Buildbucket for the build status
[13:31:13.628] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:112:0) after 1m0s
[13:32:13.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:112:0)
[13:32:13.620] Timer tick, asking Buildbucket for the build status
[13:32:13.998] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:113:0) after 1m0s
[13:33:13.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:113:0)
[13:33:13.990] Timer tick, asking Buildbucket for the build status
[13:33:14.100] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:14.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:114:0) after 1m0s
[13:34:14.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:114:0)
[13:34:14.087] Timer tick, asking Buildbucket for the build status
[13:34:14.129] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:14.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:115:0) after 1m0s
[13:35:14.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:115:0)
[13:35:14.134] Timer tick, asking Buildbucket for the build status
[13:35:14.177] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:14.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:116:0) after 1m0s
[13:36:14.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:116:0)
[13:36:14.228] Timer tick, asking Buildbucket for the build status
[13:36:14.270] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:14.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:117:0) after 1m0s
[13:37:14.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:117:0)
[13:37:14.312] Timer tick, asking Buildbucket for the build status
[13:37:14.369] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:14.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:118:0) after 1m0s
[13:38:14.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:118:0)
[13:38:14.385] Timer tick, asking Buildbucket for the build status
[13:38:14.433] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:14.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:119:0) after 1m0s
[13:39:14.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:119:0)
[13:39:14.449] Timer tick, asking Buildbucket for the build status
[13:39:14.551] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:14.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:120:0) after 1m0s
[13:40:14.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:120:0)
[13:40:14.596] Timer tick, asking Buildbucket for the build status
[13:40:14.642] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:14.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:121:0) after 1m0s
[13:41:14.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:121:0)
[13:41:14.655] Timer tick, asking Buildbucket for the build status
[13:41:14.718] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:14.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:122:0) after 1m0s
[13:42:14.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:122:0)
[13:42:14.742] Timer tick, asking Buildbucket for the build status
[13:42:15.006] Build 8918559678567101328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:15.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083989740638943616:123:0) after 1m0s
[13:42:48.714] Received PubSub notification, asking Buildbucket for the build status
[13:42:48.757] Build 8918559678567101328: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:42:48.757] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553002968039058",
"created_by": "project:fuchsia",
"created_ts": "1552995717422096",
"failure_reason": "BUILD_FAILURE",
"id": "8918559678567101328",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b274d3d79930034b8c3b2e0fc2372b66ef171eb1\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-c-hgr4\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"revision\": \"b274d3d79930034b8c3b2e0fc2372b66ef171eb1\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-c-hgr4\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552995737478688",
"status": "COMPLETED",
"status_changed_ts": "1553002968417634",
"tags": [
"builder:clang",
"buildset:commit/git/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b274d3d79930034b8c3b2e0fc2372b66ef171eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083989740638943616",
"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/8918559678567101328/+/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:43ae939aacf71a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553002968420768",
"url": "https://ci.chromium.org/b/8918559678567101328",
"utcnow_ts": "1553002968747097"
}
}
[13:42:48.757] Invocation finished in 2h0m52.694922638s with status FAILED