[09:17:54.422] New invocation is queued and will start shortly
[09:17:55.531] Starting the invocation (attempt 1)
[09:17:55.574] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:17:55.575] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:17:55.575] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082368056676686352",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"4be8629e4930e2c4cffca41c8cbf438b3aa05e85\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082368056676686352",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"gitiles_ref:refs/heads/master"
]
}
[09:17:56.344] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554542275666475",
"id": "8916937994709355712",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4be8629e4930e2c4cffca41c8cbf438b3aa05e85\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554542276069475",
"tags": [
"builder:clang",
"buildset:commit/git/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082368056676686352",
"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/8916937994709355712/+/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": "1554542276069605",
"url": "https://ci.chromium.org/b/8916937994709355712",
"utcnow_ts": "1554542276335623"
}
}
[09:17:56.344] Task URL: https://ci.chromium.org/b/8916937994709355712
[09:17:56.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:2:0) after 1m0s
[09:18:01.981] Received PubSub notification, asking Buildbucket for the build status
[09:18:02.197] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:56.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:2:0)
[09:18:56.320] Timer tick, asking Buildbucket for the build status
[09:18:56.463] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:56.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:4:0) after 1m0s
[09:19:56.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:4:0)
[09:19:56.477] Timer tick, asking Buildbucket for the build status
[09:19:56.609] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:56.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:5:0) after 1m0s
[09:20:56.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:5:0)
[09:20:56.581] Timer tick, asking Buildbucket for the build status
[09:20:56.881] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:56.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:6:0) after 1m0s
[09:21:56.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:6:0)
[09:21:56.897] Timer tick, asking Buildbucket for the build status
[09:21:57.200] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:57.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:7:0) after 1m0s
[09:22:57.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:7:0)
[09:22:57.215] Timer tick, asking Buildbucket for the build status
[09:22:57.372] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:57.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:8:0) after 1m0s
[09:23:57.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:8:0)
[09:23:57.373] Timer tick, asking Buildbucket for the build status
[09:23:57.626] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:57.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:9:0) after 1m0s
[09:24:57.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:9:0)
[09:24:57.644] Timer tick, asking Buildbucket for the build status
[09:24:57.749] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:57.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:10:0) after 1m0s
[09:25:57.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:10:0)
[09:25:57.743] Timer tick, asking Buildbucket for the build status
[09:25:57.818] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:57.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:11:0) after 1m0s
[09:26:57.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:11:0)
[09:26:57.800] Timer tick, asking Buildbucket for the build status
[09:26:57.975] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:57.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:12:0) after 1m0s
[09:27:58.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:12:0)
[09:27:58.001] Timer tick, asking Buildbucket for the build status
[09:27:58.095] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:58.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:13:0) after 1m0s
[09:28:58.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:13:0)
[09:28:58.113] Timer tick, asking Buildbucket for the build status
[09:28:58.254] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:58.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:14:0) after 1m0s
[09:29:58.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:14:0)
[09:29:58.273] Timer tick, asking Buildbucket for the build status
[09:29:58.695] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:58.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:15:0) after 1m0s
[09:30:58.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:15:0)
[09:30:58.706] Timer tick, asking Buildbucket for the build status
[09:30:59.278] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:59.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:16:0) after 1m0s
[09:31:59.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:16:0)
[09:31:59.294] Timer tick, asking Buildbucket for the build status
[09:31:59.361] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:59.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:17:0) after 1m0s
[09:32:59.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:17:0)
[09:32:59.373] Timer tick, asking Buildbucket for the build status
[09:32:59.531] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:59.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:18:0) after 1m0s
[09:33:59.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:18:0)
[09:33:59.497] Timer tick, asking Buildbucket for the build status
[09:33:59.568] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:59.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:19:0) after 1m0s
[09:34:59.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:19:0)
[09:34:59.584] Timer tick, asking Buildbucket for the build status
[09:34:59.673] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:59.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:20:0) after 1m0s
[09:35:59.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:20:0)
[09:35:59.692] Timer tick, asking Buildbucket for the build status
[09:35:59.778] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:59.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:21:0) after 1m0s
[09:36:59.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:21:0)
[09:36:59.796] Timer tick, asking Buildbucket for the build status
[09:36:59.863] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:59.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:22:0) after 1m0s
[09:37:59.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:22:0)
[09:37:59.888] Timer tick, asking Buildbucket for the build status
[09:37:59.973] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:59.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:23:0) after 1m0s
[09:38:59.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:23:0)
[09:38:59.976] Timer tick, asking Buildbucket for the build status
[09:39:00.054] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:00.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:24:0) after 1m0s
[09:40:00.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:24:0)
[09:40:00.069] Timer tick, asking Buildbucket for the build status
[09:40:00.147] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:00.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:25:0) after 1m0s
[09:41:00.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:25:0)
[09:41:00.188] Timer tick, asking Buildbucket for the build status
[09:41:00.294] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:00.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:26:0) after 1m0s
[09:42:00.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:26:0)
[09:42:00.314] Timer tick, asking Buildbucket for the build status
[09:42:00.394] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:00.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:27:0) after 1m0s
[09:43:00.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:27:0)
[09:43:00.413] Timer tick, asking Buildbucket for the build status
[09:43:00.545] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:00.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:28:0) after 1m0s
[09:44:00.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:28:0)
[09:44:00.564] Timer tick, asking Buildbucket for the build status
[09:44:00.762] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:00.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:29:0) after 1m0s
[09:45:00.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:29:0)
[09:45:00.781] Timer tick, asking Buildbucket for the build status
[09:45:00.876] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:00.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:30:0) after 1m0s
[09:46:00.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:30:0)
[09:46:00.867] Timer tick, asking Buildbucket for the build status
[09:46:01.179] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:01.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:31:0) after 1m0s
[09:47:01.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:31:0)
[09:47:01.172] Timer tick, asking Buildbucket for the build status
[09:47:01.281] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:01.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:32:0) after 1m0s
[09:48:01.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:32:0)
[09:48:01.301] Timer tick, asking Buildbucket for the build status
[09:48:01.596] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:01.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:33:0) after 1m0s
[09:49:01.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:33:0)
[09:49:01.616] Timer tick, asking Buildbucket for the build status
[09:49:01.801] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:01.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:34:0) after 1m0s
[09:50:01.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:34:0)
[09:50:01.818] Timer tick, asking Buildbucket for the build status
[09:50:01.926] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:01.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:35:0) after 1m0s
[09:51:01.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:35:0)
[09:51:01.945] Timer tick, asking Buildbucket for the build status
[09:51:02.030] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:02.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:36:0) after 1m0s
[09:52:02.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:36:0)
[09:52:02.057] Timer tick, asking Buildbucket for the build status
[09:52:02.310] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:02.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:37:0) after 1m0s
[09:53:02.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:37:0)
[09:53:02.368] Timer tick, asking Buildbucket for the build status
[09:53:02.425] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:02.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:38:0) after 1m0s
[09:54:02.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:38:0)
[09:54:02.404] Timer tick, asking Buildbucket for the build status
[09:54:04.035] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:04.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:39:0) after 1m0s
[09:55:04.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:39:0)
[09:55:04.053] Timer tick, asking Buildbucket for the build status
[09:55:04.334] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:04.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:40:0) after 1m0s
[09:56:04.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:40:0)
[09:56:04.371] Timer tick, asking Buildbucket for the build status
[09:56:04.578] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:04.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:41:0) after 1m0s
[09:57:04.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:41:0)
[09:57:04.588] Timer tick, asking Buildbucket for the build status
[09:57:04.681] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:04.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:42:0) after 1m0s
[09:58:04.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:42:0)
[09:58:04.683] Timer tick, asking Buildbucket for the build status
[09:58:04.748] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:04.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:43:0) after 1m0s
[09:59:04.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:43:0)
[09:59:04.773] Timer tick, asking Buildbucket for the build status
[09:59:04.870] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:04.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:44:0) after 1m0s
[10:00:04.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:44:0)
[10:00:04.892] Timer tick, asking Buildbucket for the build status
[10:00:04.960] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:04.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:45:0) after 1m0s
[10:01:04.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:45:0)
[10:01:04.959] Timer tick, asking Buildbucket for the build status
[10:01:05.092] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:05.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:46:0) after 1m0s
[10:02:05.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:46:0)
[10:02:05.126] Timer tick, asking Buildbucket for the build status
[10:02:05.273] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:05.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:47:0) after 1m0s
[10:03:05.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:47:0)
[10:03:05.292] Timer tick, asking Buildbucket for the build status
[10:03:05.333] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:05.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:48:0) after 1m0s
[10:04:05.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:48:0)
[10:04:05.351] Timer tick, asking Buildbucket for the build status
[10:04:05.438] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:05.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:49:0) after 1m0s
[10:05:05.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:49:0)
[10:05:05.445] Timer tick, asking Buildbucket for the build status
[10:05:05.590] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:05.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:50:0) after 1m0s
[10:06:05.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:50:0)
[10:06:05.621] Timer tick, asking Buildbucket for the build status
[10:06:05.744] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:05.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:51:0) after 1m0s
[10:07:05.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:51:0)
[10:07:05.758] Timer tick, asking Buildbucket for the build status
[10:07:05.818] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:05.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:52:0) after 1m0s
[10:08:05.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:52:0)
[10:08:05.838] Timer tick, asking Buildbucket for the build status
[10:08:05.906] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:05.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:53:0) after 1m0s
[10:09:05.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:53:0)
[10:09:05.932] Timer tick, asking Buildbucket for the build status
[10:09:06.012] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:06.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:54:0) after 1m0s
[10:10:06.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:54:0)
[10:10:06.045] Timer tick, asking Buildbucket for the build status
[10:10:06.282] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:06.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:55:0) after 1m0s
[10:11:06.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:55:0)
[10:11:06.272] Timer tick, asking Buildbucket for the build status
[10:11:06.411] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:06.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:56:0) after 1m0s
[10:12:06.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:56:0)
[10:12:06.429] Timer tick, asking Buildbucket for the build status
[10:12:06.540] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:06.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:57:0) after 1m0s
[10:13:06.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:57:0)
[10:13:06.559] Timer tick, asking Buildbucket for the build status
[10:13:06.624] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:06.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:58:0) after 1m0s
[10:14:06.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:58:0)
[10:14:06.641] Timer tick, asking Buildbucket for the build status
[10:14:06.685] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:06.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:59:0) after 1m0s
[10:15:06.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:59:0)
[10:15:06.705] Timer tick, asking Buildbucket for the build status
[10:15:06.775] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:06.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:60:0) after 1m0s
[10:16:06.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:60:0)
[10:16:06.794] Timer tick, asking Buildbucket for the build status
[10:16:06.850] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:06.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:61:0) after 1m0s
[10:17:06.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:61:0)
[10:17:06.909] Timer tick, asking Buildbucket for the build status
[10:17:07.197] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:07.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:62:0) after 1m0s
[10:18:07.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:62:0)
[10:18:07.216] Timer tick, asking Buildbucket for the build status
[10:18:07.284] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:07.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:63:0) after 1m0s
[10:19:07.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:63:0)
[10:19:07.305] Timer tick, asking Buildbucket for the build status
[10:19:07.392] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:07.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:64:0) after 1m0s
[10:20:07.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:64:0)
[10:20:07.412] Timer tick, asking Buildbucket for the build status
[10:20:07.472] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:07.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:65:0) after 1m0s
[10:21:07.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:65:0)
[10:21:07.493] Timer tick, asking Buildbucket for the build status
[10:21:07.687] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:07.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:66:0) after 1m0s
[10:22:07.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:66:0)
[10:22:07.707] Timer tick, asking Buildbucket for the build status
[10:22:07.804] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:07.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:67:0) after 1m0s
[10:23:07.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:67:0)
[10:23:07.825] Timer tick, asking Buildbucket for the build status
[10:23:07.894] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:07.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:68:0) after 1m0s
[10:24:07.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:68:0)
[10:24:07.915] Timer tick, asking Buildbucket for the build status
[10:24:08.119] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:08.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:69:0) after 1m0s
[10:25:08.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:69:0)
[10:25:08.159] Timer tick, asking Buildbucket for the build status
[10:25:08.276] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:08.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:70:0) after 1m0s
[10:26:08.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:70:0)
[10:26:08.298] Timer tick, asking Buildbucket for the build status
[10:26:08.407] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:08.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:71:0) after 1m0s
[10:27:08.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:71:0)
[10:27:08.426] Timer tick, asking Buildbucket for the build status
[10:27:08.545] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:08.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:72:0) after 1m0s
[10:28:08.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:72:0)
[10:28:08.563] Timer tick, asking Buildbucket for the build status
[10:28:08.646] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:08.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:73:0) after 1m0s
[10:29:08.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:73:0)
[10:29:08.651] Timer tick, asking Buildbucket for the build status
[10:29:08.819] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:08.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:74:0) after 1m0s
[10:30:08.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:74:0)
[10:30:08.839] Timer tick, asking Buildbucket for the build status
[10:30:08.884] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:08.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:75:0) after 1m0s
[10:31:08.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:75:0)
[10:31:08.905] Timer tick, asking Buildbucket for the build status
[10:31:09.262] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:09.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:76:0) after 1m0s
[10:32:09.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:76:0)
[10:32:09.283] Timer tick, asking Buildbucket for the build status
[10:32:09.341] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:09.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:77:0) after 1m0s
[10:33:09.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:77:0)
[10:33:09.378] Timer tick, asking Buildbucket for the build status
[10:33:09.493] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:09.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:78:0) after 1m0s
[10:34:09.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:78:0)
[10:34:09.521] Timer tick, asking Buildbucket for the build status
[10:34:09.601] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:09.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:79:0) after 1m0s
[10:35:09.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:79:0)
[10:35:09.622] Timer tick, asking Buildbucket for the build status
[10:35:09.789] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:09.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:80:0) after 1m0s
[10:36:09.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:80:0)
[10:36:09.808] Timer tick, asking Buildbucket for the build status
[10:36:09.901] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:09.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:81:0) after 1m0s
[10:37:09.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:81:0)
[10:37:09.971] Timer tick, asking Buildbucket for the build status
[10:37:10.053] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:10.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:82:0) after 1m0s
[10:38:10.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:82:0)
[10:38:10.103] Timer tick, asking Buildbucket for the build status
[10:38:10.231] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:10.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:83:0) after 1m0s
[10:39:10.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:83:0)
[10:39:10.312] Timer tick, asking Buildbucket for the build status
[10:39:10.445] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:10.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:84:0) after 1m0s
[10:40:10.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:84:0)
[10:40:10.465] Timer tick, asking Buildbucket for the build status
[10:40:10.767] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:10.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:85:0) after 1m0s
[10:41:10.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:85:0)
[10:41:10.804] Timer tick, asking Buildbucket for the build status
[10:41:11.012] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:11.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:86:0) after 1m0s
[10:42:11.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:86:0)
[10:42:11.030] Timer tick, asking Buildbucket for the build status
[10:42:11.189] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:11.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:87:0) after 1m0s
[10:43:11.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:87:0)
[10:43:11.210] Timer tick, asking Buildbucket for the build status
[10:43:11.404] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:11.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:88:0) after 1m0s
[10:44:11.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:88:0)
[10:44:11.426] Timer tick, asking Buildbucket for the build status
[10:44:11.975] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:11.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:89:0) after 1m0s
[10:45:11.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:89:0)
[10:45:11.995] Timer tick, asking Buildbucket for the build status
[10:45:12.037] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:12.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:90:0) after 1m0s
[10:46:12.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:90:0)
[10:46:12.060] Timer tick, asking Buildbucket for the build status
[10:46:12.313] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:12.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:91:0) after 1m0s
[10:47:12.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:91:0)
[10:47:12.334] Timer tick, asking Buildbucket for the build status
[10:47:12.460] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:12.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:92:0) after 1m0s
[10:48:12.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:92:0)
[10:48:12.481] Timer tick, asking Buildbucket for the build status
[10:48:12.594] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:12.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:93:0) after 1m0s
[10:49:12.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:93:0)
[10:49:12.613] Timer tick, asking Buildbucket for the build status
[10:49:12.683] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:12.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:94:0) after 1m0s
[10:50:12.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:94:0)
[10:50:12.707] Timer tick, asking Buildbucket for the build status
[10:50:12.787] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:12.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:95:0) after 1m0s
[10:51:12.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:95:0)
[10:51:12.806] Timer tick, asking Buildbucket for the build status
[10:51:12.869] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:12.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:96:0) after 1m0s
[10:52:12.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:96:0)
[10:52:12.870] Timer tick, asking Buildbucket for the build status
[10:52:12.949] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:12.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:97:0) after 1m0s
[10:53:12.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:97:0)
[10:53:12.972] Timer tick, asking Buildbucket for the build status
[10:53:13.042] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:13.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:98:0) after 1m0s
[10:54:13.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:98:0)
[10:54:13.062] Timer tick, asking Buildbucket for the build status
[10:54:13.123] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:13.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:99:0) after 1m0s
[10:55:13.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:99:0)
[10:55:13.164] Timer tick, asking Buildbucket for the build status
[10:55:13.221] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:13.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:100:0) after 1m0s
[10:56:13.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:100:0)
[10:56:13.264] Timer tick, asking Buildbucket for the build status
[10:56:13.333] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:13.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:101:0) after 1m0s
[10:57:13.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:101:0)
[10:57:13.354] Timer tick, asking Buildbucket for the build status
[10:57:13.410] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:13.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:102:0) after 1m0s
[10:58:13.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:102:0)
[10:58:13.432] Timer tick, asking Buildbucket for the build status
[10:58:13.572] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:13.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:103:0) after 1m0s
[10:59:13.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:103:0)
[10:59:13.618] Timer tick, asking Buildbucket for the build status
[10:59:13.766] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:13.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:104:0) after 1m0s
[11:00:13.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:104:0)
[11:00:13.776] Timer tick, asking Buildbucket for the build status
[11:00:13.840] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:13.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:105:0) after 1m0s
[11:01:13.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:105:0)
[11:01:13.856] Timer tick, asking Buildbucket for the build status
[11:01:13.927] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:13.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:106:0) after 1m0s
[11:02:13.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:106:0)
[11:02:13.949] Timer tick, asking Buildbucket for the build status
[11:02:14.039] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:14.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:107:0) after 1m0s
[11:03:14.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:107:0)
[11:03:14.086] Timer tick, asking Buildbucket for the build status
[11:03:14.151] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:14.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:108:0) after 1m0s
[11:04:14.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:108:0)
[11:04:14.121] Timer tick, asking Buildbucket for the build status
[11:04:14.237] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:14.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:109:0) after 1m0s
[11:05:14.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:109:0)
[11:05:14.268] Timer tick, asking Buildbucket for the build status
[11:05:14.335] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:14.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:110:0) after 1m0s
[11:06:14.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:110:0)
[11:06:14.358] Timer tick, asking Buildbucket for the build status
[11:06:14.558] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:14.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:111:0) after 1m0s
[11:07:14.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:111:0)
[11:07:14.580] Timer tick, asking Buildbucket for the build status
[11:07:14.753] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:14.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:112:0) after 1m0s
[11:08:14.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:112:0)
[11:08:14.774] Timer tick, asking Buildbucket for the build status
[11:08:14.815] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:14.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:113:0) after 1m0s
[11:09:14.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:113:0)
[11:09:14.840] Timer tick, asking Buildbucket for the build status
[11:09:14.944] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:14.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:114:0) after 1m0s
[11:10:14.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:114:0)
[11:10:14.974] Timer tick, asking Buildbucket for the build status
[11:10:15.094] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:15.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:115:0) after 1m0s
[11:11:15.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:115:0)
[11:11:15.099] Timer tick, asking Buildbucket for the build status
[11:11:15.149] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:15.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:116:0) after 1m0s
[11:12:15.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:116:0)
[11:12:15.121] Timer tick, asking Buildbucket for the build status
[11:12:15.181] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:15.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:117:0) after 1m0s
[11:13:15.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:117:0)
[11:13:15.187] Timer tick, asking Buildbucket for the build status
[11:13:15.326] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:15.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:118:0) after 1m0s
[11:14:15.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:118:0)
[11:14:15.349] Timer tick, asking Buildbucket for the build status
[11:14:15.430] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:15.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:119:0) after 1m0s
[11:15:15.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:119:0)
[11:15:15.472] Timer tick, asking Buildbucket for the build status
[11:15:15.569] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:15.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:120:0) after 1m0s
[11:16:15.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:120:0)
[11:16:15.571] Timer tick, asking Buildbucket for the build status
[11:16:15.714] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:15.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:121:0) after 1m0s
[11:17:15.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:121:0)
[11:17:15.708] Timer tick, asking Buildbucket for the build status
[11:17:15.796] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:15.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:122:0) after 1m0s
[11:18:15.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:122:0)
[11:18:15.833] Timer tick, asking Buildbucket for the build status
[11:18:15.984] Build 8916937994709355712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:15.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082368056676686352:123:0) after 1m0s
[11:18:24.774] Received PubSub notification, asking Buildbucket for the build status
[11:18:24.902] Build 8916937994709355712: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:18:24.902] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554549504516557",
"created_by": "project:fuchsia",
"created_ts": "1554542275666475",
"failure_reason": "BUILD_FAILURE",
"id": "8916937994709355712",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4be8629e4930e2c4cffca41c8cbf438b3aa05e85\"}}",
"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-g2q2\", \"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\": \"4be8629e4930e2c4cffca41c8cbf438b3aa05e85\", \"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-g2q2\"], \"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\": [\"4243-f38882f\"], \"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": "1554542281186552",
"status": "COMPLETED",
"status_changed_ts": "1554542281573585",
"tags": [
"builder:clang",
"buildset:commit/git/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4be8629e4930e2c4cffca41c8cbf438b3aa05e85",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082368056676686352",
"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/8916937994709355712/+/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:440ac238244f0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1554549504560076",
"url": "https://ci.chromium.org/b/8916937994709355712",
"utcnow_ts": "1554549504893636"
}
}
[11:18:24.902] Invocation finished in 2h0m30.496638222s with status FAILED