[02:53:08.072] New invocation is queued and will start shortly
[02:53:09.148] Starting the invocation (attempt 1)
[02:53:09.184] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:53:09.184] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:53:09.184] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080670922107023680",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"12450d51a2fe76a3d5f5dc52cd82716640d63ed4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080670922107023680",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"gitiles_ref:refs/heads/master"
]
}
[02:53:10.187] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556160789287249",
"id": "8915240860171220672",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"12450d51a2fe76a3d5f5dc52cd82716640d63ed4\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556160789801999",
"tags": [
"builder:clang",
"buildset:commit/git/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080670922107023680",
"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/8915240860171220672/+/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": "1556160789802268",
"url": "https://ci.chromium.org/b/8915240860171220672",
"utcnow_ts": "1556160790173899"
}
}
[02:53:10.187] Task URL: https://ci.chromium.org/b/8915240860171220672
[02:53:10.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:2:0) after 1m0s
[02:53:51.251] Received PubSub notification, asking Buildbucket for the build status
[02:53:51.343] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:10.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:2:0)
[02:54:10.205] Timer tick, asking Buildbucket for the build status
[02:54:10.253] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:10.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:4:0) after 1m0s
[02:55:10.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:4:0)
[02:55:10.276] Timer tick, asking Buildbucket for the build status
[02:55:10.451] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:10.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:5:0) after 1m0s
[02:56:10.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:5:0)
[02:56:10.503] Timer tick, asking Buildbucket for the build status
[02:56:10.569] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:10.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:6:0) after 1m0s
[02:57:10.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:6:0)
[02:57:10.593] Timer tick, asking Buildbucket for the build status
[02:57:10.631] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:10.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:7:0) after 1m0s
[02:58:10.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:7:0)
[02:58:10.650] Timer tick, asking Buildbucket for the build status
[02:58:10.790] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:10.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:8:0) after 1m0s
[02:59:10.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:8:0)
[02:59:10.782] Timer tick, asking Buildbucket for the build status
[02:59:10.960] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:10.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:9:0) after 1m0s
[03:00:10.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:9:0)
[03:00:10.950] Timer tick, asking Buildbucket for the build status
[03:00:11.009] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:11.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:10:0) after 1m0s
[03:01:11.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:10:0)
[03:01:11.003] Timer tick, asking Buildbucket for the build status
[03:01:12.254] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:12.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:11:0) after 1m0s
[03:02:12.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:11:0)
[03:02:12.238] Timer tick, asking Buildbucket for the build status
[03:02:12.312] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:12.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:12:0) after 1m0s
[03:03:12.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:12:0)
[03:03:12.334] Timer tick, asking Buildbucket for the build status
[03:03:12.370] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:12.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:13:0) after 1m0s
[03:04:12.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:13:0)
[03:04:12.387] Timer tick, asking Buildbucket for the build status
[03:04:12.478] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:12.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:14:0) after 1m0s
[03:05:12.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:14:0)
[03:05:12.496] Timer tick, asking Buildbucket for the build status
[03:05:12.585] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:12.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:15:0) after 1m0s
[03:06:12.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:15:0)
[03:06:12.605] Timer tick, asking Buildbucket for the build status
[03:06:12.664] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:12.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:16:0) after 1m0s
[03:07:12.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:16:0)
[03:07:12.694] Timer tick, asking Buildbucket for the build status
[03:07:12.735] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:12.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:17:0) after 1m0s
[03:08:12.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:17:0)
[03:08:12.753] Timer tick, asking Buildbucket for the build status
[03:08:13.115] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:13.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:18:0) after 1m0s
[03:09:13.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:18:0)
[03:09:13.134] Timer tick, asking Buildbucket for the build status
[03:09:13.175] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:13.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:19:0) after 1m0s
[03:10:14.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:19:0)
[03:10:14.651] Timer tick, asking Buildbucket for the build status
[03:10:14.708] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:14.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:20:0) after 1m0s
[03:11:14.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:20:0)
[03:11:14.706] Timer tick, asking Buildbucket for the build status
[03:11:14.748] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:14.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:21:0) after 1m0s
[03:12:14.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:21:0)
[03:12:14.778] Timer tick, asking Buildbucket for the build status
[03:12:15.013] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:15.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:22:0) after 1m0s
[03:13:15.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:22:0)
[03:13:15.012] Timer tick, asking Buildbucket for the build status
[03:13:15.061] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:15.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:23:0) after 1m0s
[03:14:15.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:23:0)
[03:14:15.063] Timer tick, asking Buildbucket for the build status
[03:14:15.126] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:15.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:24:0) after 1m0s
[03:15:15.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:24:0)
[03:15:15.102] Timer tick, asking Buildbucket for the build status
[03:15:15.151] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:15.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:25:0) after 1m0s
[03:16:15.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:25:0)
[03:16:15.168] Timer tick, asking Buildbucket for the build status
[03:16:15.250] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:15.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:26:0) after 1m0s
[03:17:15.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:26:0)
[03:17:15.294] Timer tick, asking Buildbucket for the build status
[03:17:15.343] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:15.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:27:0) after 1m0s
[03:18:15.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:27:0)
[03:18:15.516] Timer tick, asking Buildbucket for the build status
[03:18:15.593] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:15.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:28:0) after 1m0s
[03:19:15.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:28:0)
[03:19:15.591] Timer tick, asking Buildbucket for the build status
[03:19:15.648] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:15.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:29:0) after 1m0s
[03:20:15.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:29:0)
[03:20:15.657] Timer tick, asking Buildbucket for the build status
[03:20:15.738] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:15.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:30:0) after 1m0s
[03:21:15.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:30:0)
[03:21:15.745] Timer tick, asking Buildbucket for the build status
[03:21:15.799] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:15.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:31:0) after 1m0s
[03:22:15.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:31:0)
[03:22:15.800] Timer tick, asking Buildbucket for the build status
[03:22:16.035] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:16.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:32:0) after 1m0s
[03:23:16.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:32:0)
[03:23:16.094] Timer tick, asking Buildbucket for the build status
[03:23:16.158] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:16.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:33:0) after 1m0s
[03:24:16.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:33:0)
[03:24:16.159] Timer tick, asking Buildbucket for the build status
[03:24:16.218] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:16.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:34:0) after 1m0s
[03:25:16.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:34:0)
[03:25:16.299] Timer tick, asking Buildbucket for the build status
[03:25:16.484] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:16.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:35:0) after 1m0s
[03:26:16.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:35:0)
[03:26:16.478] Timer tick, asking Buildbucket for the build status
[03:26:16.607] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:16.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:36:0) after 1m0s
[03:27:16.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:36:0)
[03:27:16.593] Timer tick, asking Buildbucket for the build status
[03:27:16.635] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:16.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:37:0) after 1m0s
[03:28:16.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:37:0)
[03:28:16.627] Timer tick, asking Buildbucket for the build status
[03:28:16.744] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:16.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:38:0) after 1m0s
[03:29:16.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:38:0)
[03:29:16.712] Timer tick, asking Buildbucket for the build status
[03:29:16.867] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:16.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:39:0) after 1m0s
[03:30:16.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:39:0)
[03:30:16.885] Timer tick, asking Buildbucket for the build status
[03:30:16.955] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:16.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:40:0) after 1m0s
[03:31:17.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:40:0)
[03:31:17.009] Timer tick, asking Buildbucket for the build status
[03:31:17.073] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:17.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:41:0) after 1m0s
[03:32:17.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:41:0)
[03:32:17.096] Timer tick, asking Buildbucket for the build status
[03:32:17.199] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:17.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:42:0) after 1m0s
[03:33:17.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:42:0)
[03:33:17.203] Timer tick, asking Buildbucket for the build status
[03:33:17.310] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:17.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:43:0) after 1m0s
[03:34:17.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:43:0)
[03:34:17.329] Timer tick, asking Buildbucket for the build status
[03:34:17.551] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:17.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:44:0) after 1m0s
[03:35:17.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:44:0)
[03:35:17.561] Timer tick, asking Buildbucket for the build status
[03:35:17.600] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:17.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:45:0) after 1m0s
[03:36:17.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:45:0)
[03:36:17.620] Timer tick, asking Buildbucket for the build status
[03:36:17.833] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:17.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:46:0) after 1m0s
[03:37:17.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:46:0)
[03:37:17.835] Timer tick, asking Buildbucket for the build status
[03:37:17.945] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:17.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:47:0) after 1m0s
[03:38:17.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:47:0)
[03:38:17.964] Timer tick, asking Buildbucket for the build status
[03:38:18.054] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:18.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:48:0) after 1m0s
[03:39:18.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:48:0)
[03:39:18.029] Timer tick, asking Buildbucket for the build status
[03:39:18.139] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:18.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:49:0) after 1m0s
[03:40:18.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:49:0)
[03:40:18.120] Timer tick, asking Buildbucket for the build status
[03:40:18.592] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:18.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:50:0) after 1m0s
[03:41:18.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:50:0)
[03:41:18.615] Timer tick, asking Buildbucket for the build status
[03:41:18.692] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:18.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:51:0) after 1m0s
[03:42:18.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:51:0)
[03:42:18.715] Timer tick, asking Buildbucket for the build status
[03:42:18.786] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:18.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:52:0) after 1m0s
[03:43:18.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:52:0)
[03:43:18.778] Timer tick, asking Buildbucket for the build status
[03:43:18.829] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:18.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:53:0) after 1m0s
[03:44:18.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:53:0)
[03:44:18.851] Timer tick, asking Buildbucket for the build status
[03:44:18.923] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:18.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:54:0) after 1m0s
[03:45:18.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:54:0)
[03:45:18.896] Timer tick, asking Buildbucket for the build status
[03:45:18.931] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:18.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:55:0) after 1m0s
[03:46:18.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:55:0)
[03:46:18.949] Timer tick, asking Buildbucket for the build status
[03:46:19.027] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:19.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:56:0) after 1m0s
[03:47:19.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:56:0)
[03:47:19.019] Timer tick, asking Buildbucket for the build status
[03:47:19.068] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:19.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:57:0) after 1m0s
[03:48:19.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:57:0)
[03:48:19.088] Timer tick, asking Buildbucket for the build status
[03:48:19.145] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:19.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:58:0) after 1m0s
[03:49:19.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:58:0)
[03:49:19.167] Timer tick, asking Buildbucket for the build status
[03:49:19.223] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:19.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:59:0) after 1m0s
[03:50:19.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:59:0)
[03:50:19.208] Timer tick, asking Buildbucket for the build status
[03:50:19.573] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:19.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:60:0) after 1m0s
[03:51:19.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:60:0)
[03:51:19.637] Timer tick, asking Buildbucket for the build status
[03:51:19.719] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:19.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:61:0) after 1m0s
[03:52:19.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:61:0)
[03:52:19.706] Timer tick, asking Buildbucket for the build status
[03:52:19.756] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:19.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:62:0) after 1m0s
[03:53:19.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:62:0)
[03:53:19.779] Timer tick, asking Buildbucket for the build status
[03:53:19.815] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:19.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:63:0) after 1m0s
[03:54:19.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:63:0)
[03:54:19.827] Timer tick, asking Buildbucket for the build status
[03:54:19.965] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:19.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:64:0) after 1m0s
[03:55:19.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:64:0)
[03:55:19.984] Timer tick, asking Buildbucket for the build status
[03:55:20.265] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:20.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:65:0) after 1m0s
[03:56:20.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:65:0)
[03:56:20.286] Timer tick, asking Buildbucket for the build status
[03:56:20.474] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:20.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:66:0) after 1m0s
[03:57:20.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:66:0)
[03:57:20.495] Timer tick, asking Buildbucket for the build status
[03:57:20.578] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:20.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:67:0) after 1m0s
[03:58:20.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:67:0)
[03:58:20.601] Timer tick, asking Buildbucket for the build status
[03:58:20.661] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:20.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:68:0) after 1m0s
[03:59:20.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:68:0)
[03:59:20.640] Timer tick, asking Buildbucket for the build status
[03:59:20.685] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:20.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:69:0) after 1m0s
[04:00:20.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:69:0)
[04:00:20.706] Timer tick, asking Buildbucket for the build status
[04:00:20.774] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:20.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:70:0) after 1m0s
[04:01:20.787] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:70:0)
[04:01:20.787] Timer tick, asking Buildbucket for the build status
[04:01:20.969] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:20.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:71:0) after 1m0s
[04:02:20.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:71:0)
[04:02:20.992] Timer tick, asking Buildbucket for the build status
[04:02:21.059] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:21.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:72:0) after 1m0s
[04:03:21.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:72:0)
[04:03:21.088] Timer tick, asking Buildbucket for the build status
[04:03:21.223] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:21.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:73:0) after 1m0s
[04:04:21.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:73:0)
[04:04:21.242] Timer tick, asking Buildbucket for the build status
[04:04:21.376] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:21.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:74:0) after 1m0s
[04:05:21.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:74:0)
[04:05:21.398] Timer tick, asking Buildbucket for the build status
[04:05:21.500] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:21.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:75:0) after 1m0s
[04:06:21.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:75:0)
[04:06:21.498] Timer tick, asking Buildbucket for the build status
[04:06:21.647] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:21.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:76:0) after 1m0s
[04:07:21.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:76:0)
[04:07:21.706] Timer tick, asking Buildbucket for the build status
[04:07:21.749] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:21.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:77:0) after 1m0s
[04:08:21.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:77:0)
[04:08:21.785] Timer tick, asking Buildbucket for the build status
[04:08:21.857] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:21.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:78:0) after 1m0s
[04:09:21.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:78:0)
[04:09:21.838] Timer tick, asking Buildbucket for the build status
[04:09:21.933] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:21.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:79:0) after 1m0s
[04:10:21.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:79:0)
[04:10:21.953] Timer tick, asking Buildbucket for the build status
[04:10:22.162] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:22.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:80:0) after 1m0s
[04:11:22.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:80:0)
[04:11:22.186] Timer tick, asking Buildbucket for the build status
[04:11:22.253] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:22.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:81:0) after 1m0s
[04:12:22.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:81:0)
[04:12:22.262] Timer tick, asking Buildbucket for the build status
[04:12:22.330] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:22.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:82:0) after 1m0s
[04:13:22.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:82:0)
[04:13:22.324] Timer tick, asking Buildbucket for the build status
[04:13:22.373] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:22.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:83:0) after 1m0s
[04:14:22.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:83:0)
[04:14:22.375] Timer tick, asking Buildbucket for the build status
[04:14:22.486] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:22.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:84:0) after 1m0s
[04:15:22.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:84:0)
[04:15:22.531] Timer tick, asking Buildbucket for the build status
[04:15:22.575] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:22.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:85:0) after 1m0s
[04:16:22.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:85:0)
[04:16:22.598] Timer tick, asking Buildbucket for the build status
[04:16:22.766] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:22.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:86:0) after 1m0s
[04:17:22.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:86:0)
[04:17:22.772] Timer tick, asking Buildbucket for the build status
[04:17:22.809] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:22.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:87:0) after 1m0s
[04:18:22.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:87:0)
[04:18:22.853] Timer tick, asking Buildbucket for the build status
[04:18:22.920] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:22.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:88:0) after 1m0s
[04:19:22.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:88:0)
[04:19:22.924] Timer tick, asking Buildbucket for the build status
[04:19:22.960] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:22.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:89:0) after 1m0s
[04:20:22.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:89:0)
[04:20:22.975] Timer tick, asking Buildbucket for the build status
[04:20:23.028] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:23.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:90:0) after 1m0s
[04:21:23.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:90:0)
[04:21:23.047] Timer tick, asking Buildbucket for the build status
[04:21:23.107] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:23.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:91:0) after 1m0s
[04:22:23.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:91:0)
[04:22:23.129] Timer tick, asking Buildbucket for the build status
[04:22:23.320] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:23.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:92:0) after 1m0s
[04:23:23.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:92:0)
[04:23:23.328] Timer tick, asking Buildbucket for the build status
[04:23:23.366] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:23.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:93:0) after 1m0s
[04:24:23.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:93:0)
[04:24:23.381] Timer tick, asking Buildbucket for the build status
[04:24:23.495] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:23.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:94:0) after 1m0s
[04:25:23.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:94:0)
[04:25:23.476] Timer tick, asking Buildbucket for the build status
[04:25:23.595] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:23.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:95:0) after 1m0s
[04:26:23.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:95:0)
[04:26:23.618] Timer tick, asking Buildbucket for the build status
[04:26:23.766] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:23.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:96:0) after 1m0s
[04:27:23.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:96:0)
[04:27:23.790] Timer tick, asking Buildbucket for the build status
[04:27:23.832] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:23.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:97:0) after 1m0s
[04:28:23.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:97:0)
[04:28:23.853] Timer tick, asking Buildbucket for the build status
[04:28:23.933] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:23.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:98:0) after 1m0s
[04:29:23.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:98:0)
[04:29:23.906] Timer tick, asking Buildbucket for the build status
[04:29:23.948] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:23.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:99:0) after 1m0s
[04:30:23.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:99:0)
[04:30:23.933] Timer tick, asking Buildbucket for the build status
[04:30:24.067] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:24.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:100:0) after 1m0s
[04:31:24.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:100:0)
[04:31:24.103] Timer tick, asking Buildbucket for the build status
[04:31:24.359] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:24.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:101:0) after 1m0s
[04:32:24.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:101:0)
[04:32:24.449] Timer tick, asking Buildbucket for the build status
[04:32:24.586] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:24.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:102:0) after 1m0s
[04:33:24.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:102:0)
[04:33:24.609] Timer tick, asking Buildbucket for the build status
[04:33:24.647] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:24.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:103:0) after 1m0s
[04:34:24.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:103:0)
[04:34:24.684] Timer tick, asking Buildbucket for the build status
[04:34:24.746] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:24.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:104:0) after 1m0s
[04:35:24.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:104:0)
[04:35:24.745] Timer tick, asking Buildbucket for the build status
[04:35:24.884] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:24.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:105:0) after 1m0s
[04:36:24.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:105:0)
[04:36:24.923] Timer tick, asking Buildbucket for the build status
[04:36:24.961] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:24.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:106:0) after 1m0s
[04:37:24.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:106:0)
[04:37:24.996] Timer tick, asking Buildbucket for the build status
[04:37:25.045] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:25.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:107:0) after 1m0s
[04:38:25.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:107:0)
[04:38:25.089] Timer tick, asking Buildbucket for the build status
[04:38:25.188] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:25.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:108:0) after 1m0s
[04:39:25.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:108:0)
[04:39:25.214] Timer tick, asking Buildbucket for the build status
[04:39:25.307] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:25.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:109:0) after 1m0s
[04:40:25.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:109:0)
[04:40:25.329] Timer tick, asking Buildbucket for the build status
[04:40:25.384] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:25.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:110:0) after 1m0s
[04:41:25.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:110:0)
[04:41:25.413] Timer tick, asking Buildbucket for the build status
[04:41:25.531] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:25.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:111:0) after 1m0s
[04:42:25.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:111:0)
[04:42:25.552] Timer tick, asking Buildbucket for the build status
[04:42:25.608] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:25.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:112:0) after 1m0s
[04:43:25.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:112:0)
[04:43:25.660] Timer tick, asking Buildbucket for the build status
[04:43:25.703] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:25.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:113:0) after 1m0s
[04:44:25.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:113:0)
[04:44:25.726] Timer tick, asking Buildbucket for the build status
[04:44:25.803] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:25.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:114:0) after 1m0s
[04:45:25.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:114:0)
[04:45:25.779] Timer tick, asking Buildbucket for the build status
[04:45:25.827] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:25.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:115:0) after 1m0s
[04:46:25.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:115:0)
[04:46:25.808] Timer tick, asking Buildbucket for the build status
[04:46:26.147] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:26.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:116:0) after 1m0s
[04:47:26.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:116:0)
[04:47:26.170] Timer tick, asking Buildbucket for the build status
[04:47:26.356] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:26.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:117:0) after 1m0s
[04:48:26.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:117:0)
[04:48:26.358] Timer tick, asking Buildbucket for the build status
[04:48:26.425] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:26.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:118:0) after 1m0s
[04:49:26.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:118:0)
[04:49:26.501] Timer tick, asking Buildbucket for the build status
[04:49:26.584] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:26.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:119:0) after 1m0s
[04:50:26.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:119:0)
[04:50:26.660] Timer tick, asking Buildbucket for the build status
[04:50:26.882] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:26.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:120:0) after 1m0s
[04:51:26.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:120:0)
[04:51:26.904] Timer tick, asking Buildbucket for the build status
[04:51:26.945] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:26.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:121:0) after 1m0s
[04:52:26.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:121:0)
[04:52:26.965] Timer tick, asking Buildbucket for the build status
[04:52:27.019] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:27.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:122:0) after 1m0s
[04:53:27.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:122:0)
[04:53:27.044] Timer tick, asking Buildbucket for the build status
[04:53:27.124] Build 8915240860171220672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:27.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080670922107023680:123:0) after 1m0s
[04:54:26.284] Received PubSub notification, asking Buildbucket for the build status
[04:54:26.754] Build 8915240860171220672: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:54:26.754] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556168065965901",
"created_by": "project:fuchsia",
"created_ts": "1556160789287249",
"failure_reason": "BUILD_FAILURE",
"id": "8915240860171220672",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"12450d51a2fe76a3d5f5dc52cd82716640d63ed4\"}}",
"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-dgrc\", \"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\": \"12450d51a2fe76a3d5f5dc52cd82716640d63ed4\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-dgrc\"], \"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\": [\"4274-a0a5f8c\"], \"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": "1556160830403567",
"status": "COMPLETED",
"status_changed_ts": "1556160830913466",
"tags": [
"builder:clang",
"buildset:commit/git/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/12450d51a2fe76a3d5f5dc52cd82716640d63ed4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080670922107023680",
"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/8915240860171220672/+/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:446b3ac6d233c810",
"user_agent:luci-scheduler"
],
"updated_ts": "1556168066027072",
"url": "https://ci.chromium.org/b/8915240860171220672",
"utcnow_ts": "1556168066693655"
}
}
[04:54:26.754] Invocation finished in 2h1m18.697078522s with status FAILED