[19:34:17.393] New invocation is queued and will start shortly
[19:34:18.573] Starting the invocation (attempt 1)
[19:34:18.639] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:34:18.641] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:34:18.642] Buildbucket request:
{
"bucket": "luci.v8.ci.br.beta",
"client_operation_id": "9021085727935068784",
"parameters_json": "{\"builder_name\":\"V8 Linux - full debug\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/branch-heads/8.9\",\"repo\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"},\"id\":\"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.9@6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\",\"title\":\"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\",\"url\":\"https://chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}]},\"branch\":\"refs/branch-heads/8.9\",\"repository\":\"https://chromium.googlesource.com/v8/v8\",\"revision\":\"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:V8 Linux - full debug",
"scheduler_invocation_id:9021085727935068784",
"scheduler_job_id:v8/ci.br.beta-V8 Linux - full debug",
"user_agent:luci-scheduler",
"buildset:commit/git/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"gitiles_ref:refs/branch-heads/8.9"
]
}
[19:34:19.906] Buildbucket response:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"created_by": "project:v8",
"created_ts": "1612985658773426",
"id": "8855655665824463504",
"parameters_json": "{\"builder_name\": \"V8 Linux - full debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.9\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.9@6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"title\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}]}, \"branch\": \"refs/branch-heads/8.9\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}}",
"project": "v8",
"result_details_json": "{\"properties\": {}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612985659514396",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Linux - full debug/161",
"builder:V8 Linux - full debug",
"buildset:commit/git/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"gitiles_ref:refs/branch-heads/8.9",
"scheduler_invocation_id:9021085727935068784",
"scheduler_job_id:v8/ci.br.beta-V8 Linux - full debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8855655665824463504/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612985659514525",
"url": "https://ci.chromium.org/b/8855655665824463504",
"utcnow_ts": "1612985659894935"
}
}
[19:34:19.906] Task URL: https://ci.chromium.org/b/8855655665824463504
[19:34:19.907] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:2:0) after 1m0s
[19:34:53.672] Received PubSub notification, asking Buildbucket for the build status
[19:34:54.330] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:19.911] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:2:0)
[19:35:19.911] Timer tick, asking Buildbucket for the build status
[19:35:20.181] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:20.181] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:4:0) after 1m0s
[19:36:20.195] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:4:0)
[19:36:20.195] Timer tick, asking Buildbucket for the build status
[19:36:20.389] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:20.389] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:5:0) after 1m0s
[19:37:20.456] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:5:0)
[19:37:20.456] Timer tick, asking Buildbucket for the build status
[19:37:20.776] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:20.776] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:6:0) after 1m0s
[19:38:20.802] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:6:0)
[19:38:20.802] Timer tick, asking Buildbucket for the build status
[19:38:20.943] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:20.943] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:7:0) after 1m0s
[19:39:21.030] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:7:0)
[19:39:21.030] Timer tick, asking Buildbucket for the build status
[19:39:21.121] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:21.121] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:8:0) after 1m0s
[19:40:21.179] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:8:0)
[19:40:21.179] Timer tick, asking Buildbucket for the build status
[19:40:21.290] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:21.290] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:9:0) after 1m0s
[19:41:21.317] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:9:0)
[19:41:21.317] Timer tick, asking Buildbucket for the build status
[19:41:21.423] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:21.423] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:10:0) after 1m0s
[19:42:21.433] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:10:0)
[19:42:21.433] Timer tick, asking Buildbucket for the build status
[19:42:21.615] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:21.615] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:11:0) after 1m0s
[19:43:21.703] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:11:0)
[19:43:21.703] Timer tick, asking Buildbucket for the build status
[19:43:21.809] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:21.809] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:12:0) after 1m0s
[19:44:21.843] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:12:0)
[19:44:21.843] Timer tick, asking Buildbucket for the build status
[19:44:22.095] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:22.095] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:13:0) after 1m0s
[19:45:22.152] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:13:0)
[19:45:22.152] Timer tick, asking Buildbucket for the build status
[19:45:22.268] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:22.268] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:14:0) after 1m0s
[19:46:22.312] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:14:0)
[19:46:22.312] Timer tick, asking Buildbucket for the build status
[19:46:22.403] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:22.404] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:15:0) after 1m0s
[19:47:22.488] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:15:0)
[19:47:22.488] Timer tick, asking Buildbucket for the build status
[19:47:23.159] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:23.159] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:16:0) after 1m0s
[19:48:23.228] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:16:0)
[19:48:23.228] Timer tick, asking Buildbucket for the build status
[19:48:23.785] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:23.785] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:17:0) after 1m0s
[19:49:23.851] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:17:0)
[19:49:23.851] Timer tick, asking Buildbucket for the build status
[19:49:24.462] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:24.462] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:18:0) after 1m0s
[19:50:24.534] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:18:0)
[19:50:24.534] Timer tick, asking Buildbucket for the build status
[19:50:24.649] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:24.649] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:19:0) after 1m0s
[19:51:24.724] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:19:0)
[19:51:24.725] Timer tick, asking Buildbucket for the build status
[19:51:24.969] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:24.969] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:20:0) after 1m0s
[19:52:25.015] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:20:0)
[19:52:25.015] Timer tick, asking Buildbucket for the build status
[19:52:25.097] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:25.097] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:21:0) after 1m0s
[19:53:25.142] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:21:0)
[19:53:25.142] Timer tick, asking Buildbucket for the build status
[19:53:25.224] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:25.224] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:22:0) after 1m0s
[19:54:25.265] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:22:0)
[19:54:25.265] Timer tick, asking Buildbucket for the build status
[19:54:25.518] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:25.518] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:23:0) after 1m0s
[19:55:25.542] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:23:0)
[19:55:25.542] Timer tick, asking Buildbucket for the build status
[19:55:25.628] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:25.628] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:24:0) after 1m0s
[19:56:25.646] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:24:0)
[19:56:25.646] Timer tick, asking Buildbucket for the build status
[19:56:25.820] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:25.820] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:25:0) after 1m0s
[19:57:25.874] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:25:0)
[19:57:25.874] Timer tick, asking Buildbucket for the build status
[19:57:26.820] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:26.820] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:26:0) after 1m0s
[19:58:26.893] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:26:0)
[19:58:26.893] Timer tick, asking Buildbucket for the build status
[19:58:27.032] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:27.032] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:27:0) after 1m0s
[19:59:27.188] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:27:0)
[19:59:27.188] Timer tick, asking Buildbucket for the build status
[19:59:27.299] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:27.299] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:28:0) after 1m0s
[20:00:27.290] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:28:0)
[20:00:27.290] Timer tick, asking Buildbucket for the build status
[20:00:28.305] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:28.305] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:29:0) after 1m0s
[20:01:28.421] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:29:0)
[20:01:28.421] Timer tick, asking Buildbucket for the build status
[20:01:28.535] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:28.535] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:30:0) after 1m0s
[20:02:28.537] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:30:0)
[20:02:28.537] Timer tick, asking Buildbucket for the build status
[20:02:28.677] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:28.677] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:31:0) after 1m0s
[20:03:28.691] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:31:0)
[20:03:28.691] Timer tick, asking Buildbucket for the build status
[20:03:28.846] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:28.846] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:32:0) after 1m0s
[20:04:28.880] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:32:0)
[20:04:28.880] Timer tick, asking Buildbucket for the build status
[20:04:29.056] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:29.056] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:33:0) after 1m0s
[20:05:29.132] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:33:0)
[20:05:29.132] Timer tick, asking Buildbucket for the build status
[20:05:29.598] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:29.598] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:34:0) after 1m0s
[20:06:29.627] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:34:0)
[20:06:29.627] Timer tick, asking Buildbucket for the build status
[20:06:30.267] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:30.267] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:35:0) after 1m0s
[20:07:30.345] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:35:0)
[20:07:30.345] Timer tick, asking Buildbucket for the build status
[20:07:30.529] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:30.529] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:36:0) after 1m0s
[20:08:30.590] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:36:0)
[20:08:30.590] Timer tick, asking Buildbucket for the build status
[20:08:30.702] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.702] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:37:0) after 1m0s
[20:09:30.831] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:37:0)
[20:09:30.831] Timer tick, asking Buildbucket for the build status
[20:09:30.911] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:30.911] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:38:0) after 1m0s
[20:10:30.957] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:38:0)
[20:10:30.957] Timer tick, asking Buildbucket for the build status
[20:10:31.349] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:31.349] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:39:0) after 1m0s
[20:11:31.339] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:39:0)
[20:11:31.339] Timer tick, asking Buildbucket for the build status
[20:11:31.416] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:31.416] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:40:0) after 1m0s
[20:12:31.446] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:40:0)
[20:12:31.446] Timer tick, asking Buildbucket for the build status
[20:12:31.555] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:31.555] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:41:0) after 1m0s
[20:13:31.606] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:41:0)
[20:13:31.606] Timer tick, asking Buildbucket for the build status
[20:13:31.732] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:31.732] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:42:0) after 1m0s
[20:14:31.857] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:42:0)
[20:14:31.857] Timer tick, asking Buildbucket for the build status
[20:14:32.233] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:32.233] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:43:0) after 1m0s
[20:15:32.221] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:43:0)
[20:15:32.221] Timer tick, asking Buildbucket for the build status
[20:15:32.942] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:32.942] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:44:0) after 1m0s
[20:16:33.033] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:44:0)
[20:16:33.033] Timer tick, asking Buildbucket for the build status
[20:16:33.180] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:33.180] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:45:0) after 1m0s
[20:17:33.230] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:45:0)
[20:17:33.230] Timer tick, asking Buildbucket for the build status
[20:17:33.355] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:33.355] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:46:0) after 1m0s
[20:18:33.417] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:46:0)
[20:18:33.417] Timer tick, asking Buildbucket for the build status
[20:18:33.531] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:33.531] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:47:0) after 1m0s
[20:19:33.527] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:47:0)
[20:19:33.527] Timer tick, asking Buildbucket for the build status
[20:19:33.863] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:33.864] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:48:0) after 1m0s
[20:20:33.893] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:48:0)
[20:20:33.893] Timer tick, asking Buildbucket for the build status
[20:20:34.071] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:34.071] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:49:0) after 1m0s
[20:21:34.099] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:49:0)
[20:21:34.099] Timer tick, asking Buildbucket for the build status
[20:21:34.329] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.329] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:50:0) after 1m0s
[20:22:34.349] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:50:0)
[20:22:34.349] Timer tick, asking Buildbucket for the build status
[20:22:34.518] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:34.518] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:51:0) after 1m0s
[20:23:34.525] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:51:0)
[20:23:34.525] Timer tick, asking Buildbucket for the build status
[20:23:34.734] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:34.734] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:52:0) after 1m0s
[20:24:34.856] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:52:0)
[20:24:34.856] Timer tick, asking Buildbucket for the build status
[20:24:34.985] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:34.985] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:53:0) after 1m0s
[20:25:35.031] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:53:0)
[20:25:35.031] Timer tick, asking Buildbucket for the build status
[20:25:35.156] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:35.156] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:54:0) after 1m0s
[20:26:35.187] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:54:0)
[20:26:35.187] Timer tick, asking Buildbucket for the build status
[20:26:35.322] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:35.322] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:55:0) after 1m0s
[20:27:35.420] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:55:0)
[20:27:35.420] Timer tick, asking Buildbucket for the build status
[20:27:35.492] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:35.492] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:56:0) after 1m0s
[20:28:35.520] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:56:0)
[20:28:35.520] Timer tick, asking Buildbucket for the build status
[20:28:35.735] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:35.735] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:57:0) after 1m0s
[20:29:35.748] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:57:0)
[20:29:35.748] Timer tick, asking Buildbucket for the build status
[20:29:35.833] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:35.833] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:58:0) after 1m0s
[20:30:35.863] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:58:0)
[20:30:35.863] Timer tick, asking Buildbucket for the build status
[20:30:35.975] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:35.975] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:59:0) after 1m0s
[20:31:36.033] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:59:0)
[20:31:36.033] Timer tick, asking Buildbucket for the build status
[20:31:36.447] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:36.447] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:60:0) after 1m0s
[20:32:36.548] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:60:0)
[20:32:36.548] Timer tick, asking Buildbucket for the build status
[20:32:44.828] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:44.828] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:61:0) after 1m0s
[20:33:44.884] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:61:0)
[20:33:44.884] Timer tick, asking Buildbucket for the build status
[20:33:45.012] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:45.013] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:62:0) after 1m0s
[20:34:45.123] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:62:0)
[20:34:45.123] Timer tick, asking Buildbucket for the build status
[20:34:45.210] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:45.210] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:63:0) after 1m0s
[20:35:45.212] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:63:0)
[20:35:45.212] Timer tick, asking Buildbucket for the build status
[20:35:45.763] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:45.763] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:64:0) after 1m0s
[20:36:45.833] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:64:0)
[20:36:45.833] Timer tick, asking Buildbucket for the build status
[20:36:46.043] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:46.043] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:65:0) after 1m0s
[20:37:46.067] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:65:0)
[20:37:46.067] Timer tick, asking Buildbucket for the build status
[20:37:46.257] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:46.257] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:66:0) after 1m0s
[20:38:46.285] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:66:0)
[20:38:46.285] Timer tick, asking Buildbucket for the build status
[20:38:46.385] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:46.385] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:67:0) after 1m0s
[20:39:46.441] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:67:0)
[20:39:46.441] Timer tick, asking Buildbucket for the build status
[20:39:46.529] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:46.529] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:68:0) after 1m0s
[20:40:46.534] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:68:0)
[20:40:46.534] Timer tick, asking Buildbucket for the build status
[20:40:46.650] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:46.650] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:69:0) after 1m0s
[20:41:46.725] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:69:0)
[20:41:46.725] Timer tick, asking Buildbucket for the build status
[20:41:46.890] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:46.890] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:70:0) after 1m0s
[20:42:46.909] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:70:0)
[20:42:46.909] Timer tick, asking Buildbucket for the build status
[20:42:46.990] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:46.990] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:71:0) after 1m0s
[20:43:47.022] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:71:0)
[20:43:47.022] Timer tick, asking Buildbucket for the build status
[20:43:47.115] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:47.115] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:72:0) after 1m0s
[20:44:47.152] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:72:0)
[20:44:47.152] Timer tick, asking Buildbucket for the build status
[20:44:47.273] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:47.273] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:73:0) after 1m0s
[20:45:47.324] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:73:0)
[20:45:47.324] Timer tick, asking Buildbucket for the build status
[20:45:47.641] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:47.641] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:74:0) after 1m0s
[20:46:47.657] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:74:0)
[20:46:47.657] Timer tick, asking Buildbucket for the build status
[20:46:47.829] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:47.829] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:75:0) after 1m0s
[20:47:47.876] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:75:0)
[20:47:47.876] Timer tick, asking Buildbucket for the build status
[20:47:48.125] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:48.125] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:76:0) after 1m0s
[20:48:48.156] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:76:0)
[20:48:48.156] Timer tick, asking Buildbucket for the build status
[20:48:48.357] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:48.357] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:77:0) after 1m0s
[20:49:48.336] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:77:0)
[20:49:48.336] Timer tick, asking Buildbucket for the build status
[20:49:48.465] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:48.465] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:78:0) after 1m0s
[20:50:48.544] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:78:0)
[20:50:48.544] Timer tick, asking Buildbucket for the build status
[20:50:48.809] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:48.809] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:79:0) after 1m0s
[20:51:48.822] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:79:0)
[20:51:48.822] Timer tick, asking Buildbucket for the build status
[20:51:49.075] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:49.075] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:80:0) after 1m0s
[20:52:49.109] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:80:0)
[20:52:49.109] Timer tick, asking Buildbucket for the build status
[20:52:49.239] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:49.239] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:81:0) after 1m0s
[20:53:49.261] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:81:0)
[20:53:49.261] Timer tick, asking Buildbucket for the build status
[20:53:49.614] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:49.614] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:82:0) after 1m0s
[20:54:49.672] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:82:0)
[20:54:49.672] Timer tick, asking Buildbucket for the build status
[20:54:49.899] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:49.899] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:83:0) after 1m0s
[20:55:49.915] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:83:0)
[20:55:49.915] Timer tick, asking Buildbucket for the build status
[20:55:51.800] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:51.800] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:84:0) after 1m0s
[20:56:51.804] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:84:0)
[20:56:51.804] Timer tick, asking Buildbucket for the build status
[20:56:52.076] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:52.076] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:85:0) after 1m0s
[20:57:52.185] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:85:0)
[20:57:52.185] Timer tick, asking Buildbucket for the build status
[20:57:52.334] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:52.334] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:86:0) after 1m0s
[20:58:52.471] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:86:0)
[20:58:52.471] Timer tick, asking Buildbucket for the build status
[20:58:52.797] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:52.797] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:87:0) after 1m0s
[20:59:52.828] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:87:0)
[20:59:52.828] Timer tick, asking Buildbucket for the build status
[20:59:52.933] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:52.933] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:88:0) after 1m0s
[21:00:53.023] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:88:0)
[21:00:53.023] Timer tick, asking Buildbucket for the build status
[21:00:53.814] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:53.814] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:89:0) after 1m0s
[21:01:53.986] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:89:0)
[21:01:53.986] Timer tick, asking Buildbucket for the build status
[21:01:54.556] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:54.556] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:90:0) after 1m0s
[21:02:54.662] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:90:0)
[21:02:54.662] Timer tick, asking Buildbucket for the build status
[21:02:55.012] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:55.012] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:91:0) after 1m0s
[21:03:55.030] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:91:0)
[21:03:55.030] Timer tick, asking Buildbucket for the build status
[21:03:55.135] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:55.135] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:92:0) after 1m0s
[21:04:55.230] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:92:0)
[21:04:55.230] Timer tick, asking Buildbucket for the build status
[21:04:55.653] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:55.653] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:93:0) after 1m0s
[21:05:55.734] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:93:0)
[21:05:55.734] Timer tick, asking Buildbucket for the build status
[21:05:55.836] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:55.836] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:94:0) after 1m0s
[21:06:55.844] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:94:0)
[21:06:55.844] Timer tick, asking Buildbucket for the build status
[21:06:56.302] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:56.302] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:95:0) after 1m0s
[21:07:56.390] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:95:0)
[21:07:56.390] Timer tick, asking Buildbucket for the build status
[21:07:56.770] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:56.770] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:96:0) after 1m0s
[21:08:56.864] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:96:0)
[21:08:56.864] Timer tick, asking Buildbucket for the build status
[21:08:56.955] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:56.955] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:97:0) after 1m0s
[21:09:56.966] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:97:0)
[21:09:56.966] Timer tick, asking Buildbucket for the build status
[21:09:57.202] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:57.202] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:98:0) after 1m0s
[21:10:57.246] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:98:0)
[21:10:57.246] Timer tick, asking Buildbucket for the build status
[21:10:57.418] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:57.418] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:99:0) after 1m0s
[21:11:57.533] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:99:0)
[21:11:57.533] Timer tick, asking Buildbucket for the build status
[21:11:57.660] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:57.660] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:100:0) after 1m0s
[21:12:57.744] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:100:0)
[21:12:57.744] Timer tick, asking Buildbucket for the build status
[21:12:58.009] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:58.009] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:101:0) after 1m0s
[21:13:58.072] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:101:0)
[21:13:58.072] Timer tick, asking Buildbucket for the build status
[21:13:58.157] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:58.157] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:102:0) after 1m0s
[21:14:58.320] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:102:0)
[21:14:58.320] Timer tick, asking Buildbucket for the build status
[21:14:58.397] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:58.397] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:103:0) after 1m0s
[21:15:58.488] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:103:0)
[21:15:58.488] Timer tick, asking Buildbucket for the build status
[21:15:58.774] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:58.774] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:104:0) after 1m0s
[21:16:58.839] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:104:0)
[21:16:58.839] Timer tick, asking Buildbucket for the build status
[21:16:58.920] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:58.920] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:105:0) after 1m0s
[21:17:58.939] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:105:0)
[21:17:58.939] Timer tick, asking Buildbucket for the build status
[21:17:59.014] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:59.014] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:106:0) after 1m0s
[21:18:59.065] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:106:0)
[21:18:59.065] Timer tick, asking Buildbucket for the build status
[21:18:59.209] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:59.209] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:107:0) after 1m0s
[21:19:59.373] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:107:0)
[21:19:59.373] Timer tick, asking Buildbucket for the build status
[21:19:59.451] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:59.451] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:108:0) after 1m0s
[21:20:59.532] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:108:0)
[21:20:59.532] Timer tick, asking Buildbucket for the build status
[21:20:59.765] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:59.765] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:109:0) after 1m0s
[21:21:59.803] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:109:0)
[21:21:59.803] Timer tick, asking Buildbucket for the build status
[21:21:59.935] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:59.935] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:110:0) after 1m0s
[21:23:00.006] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:110:0)
[21:23:00.006] Timer tick, asking Buildbucket for the build status
[21:23:00.196] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:00.196] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:111:0) after 1m0s
[21:24:00.318] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:111:0)
[21:24:00.318] Timer tick, asking Buildbucket for the build status
[21:24:00.645] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:00.645] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:112:0) after 1m0s
[21:25:00.673] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:112:0)
[21:25:00.673] Timer tick, asking Buildbucket for the build status
[21:25:00.976] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:00.976] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:113:0) after 1m0s
[21:26:01.001] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:113:0)
[21:26:01.001] Timer tick, asking Buildbucket for the build status
[21:26:01.103] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:01.103] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:114:0) after 1m0s
[21:27:01.483] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:114:0)
[21:27:01.483] Timer tick, asking Buildbucket for the build status
[21:27:01.744] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:01.744] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:115:0) after 1m0s
[21:28:01.849] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:115:0)
[21:28:01.849] Timer tick, asking Buildbucket for the build status
[21:28:01.927] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:01.927] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:116:0) after 1m0s
[21:29:02.147] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:116:0)
[21:29:02.147] Timer tick, asking Buildbucket for the build status
[21:29:02.232] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:02.232] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:117:0) after 1m0s
[21:30:02.258] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:117:0)
[21:30:02.258] Timer tick, asking Buildbucket for the build status
[21:30:02.345] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:02.345] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:118:0) after 1m0s
[21:31:02.405] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:118:0)
[21:31:02.405] Timer tick, asking Buildbucket for the build status
[21:31:02.525] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:02.525] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:119:0) after 1m0s
[21:32:02.574] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:119:0)
[21:32:02.574] Timer tick, asking Buildbucket for the build status
[21:32:02.808] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:02.808] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:120:0) after 1m0s
[21:33:02.858] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:120:0)
[21:33:02.858] Timer tick, asking Buildbucket for the build status
[21:33:02.927] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:02.927] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:121:0) after 1m0s
[21:34:03.020] Handling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:121:0)
[21:34:03.020] Timer tick, asking Buildbucket for the build status
[21:34:03.119] Build 8855655665824463504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:03.119] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.beta-V8 Linux - full debug:9021085727935068784:122:0) after 1m0s
[21:34:58.321] Received PubSub notification, asking Buildbucket for the build status
[21:34:58.446] Build 8855655665824463504: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[21:34:58.446] Buildbucket build:
{
"build": {
"bucket": "luci.v8.ci.br.beta",
"canary_preference": "PROD",
"completed_ts": "1612992897775411",
"created_by": "project:v8",
"created_ts": "1612985658773426",
"failure_reason": "INFRA_FAILURE",
"id": "8855655665824463504",
"parameters_json": "{\"builder_name\": \"V8 Linux - full debug\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.9\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.9@6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"title\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}]}, \"branch\": \"refs/branch-heads/8.9\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}}",
"project": "v8",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Recipe timed out\"}, \"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com/\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/branch-heads/8.9\", \"repo\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}, \"id\": \"https://chromium.googlesource.com/v8/v8/+/refs/branch-heads/8.9@6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"title\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"url\": \"https://chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}]}, \"bot_id\": \"luci-v8-ci-xenial-38-0pfd\", \"branch\": \"refs/branch-heads/8.9\", \"builder_group\": \"client.v8\", \"buildername\": \"V8 Linux - full debug\", \"buildnumber\": 161, \"got_revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\", \"got_revision_cp\": \"refs/branch-heads/8.9@{#37}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"v8\", \"repository\": \"https://chromium.googlesource.com/v8/v8\", \"revision\": \"6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_18d91c7d645e01d7791b6a71b2dde61fb40586af70fc89de67b73b7e53b267cf_v2\", \"builder_e1f9954f18035478cd49c9df666e20bb2aa8363b19b712d6e157a0dd73e51cdd_v2\", \"builder_e3351529ef874b5cf4be15c96903351f03d7e45e076939a818634c2e1f3231fd_v2\", \"builder_fd5b6657f1d3a2f1a487e0b5530f22908da59be7cac27acc2fc249a9d5248335_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-v8-ci-xenial-38-0pfd\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.v8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"recipe infra failure: Recipe timed out\"}}",
"service_account": "v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612985691143057",
"status": "COMPLETED",
"status_changed_ts": "1612985693214691",
"tags": [
"build_address:luci.v8.ci.br.beta/V8 Linux - full debug/161",
"builder:V8 Linux - full debug",
"buildset:commit/git/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/6a1f77e02dd2a4c8f247334d7ad9c6dda31028a2",
"gitiles_ref:refs/branch-heads/8.9",
"scheduler_invocation_id:9021085727935068784",
"scheduler_job_id:v8/ci.br.beta-V8 Linux - full debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/v8/buildbucket/cr-buildbucket.appspot.com/8855655665824463504/+/annotations",
"swarming_tag:luci_project:v8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:v8",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:51a6417e5588a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1612992897828463",
"url": "https://ci.chromium.org/b/8855655665824463504",
"utcnow_ts": "1612992898437393"
}
}
[21:34:58.446] Invocation finished in 2h0m41.069298078s with status FAILED