Status: FAILED
Triggered by: -
Duration: 3 hours
Actions:

Definition (rev 8fecfaafc1abba4843234d58a712c5811f9e1de9)

buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.nacl.ci"
  builder: "linux-64-validator-opt"
>

Incoming triggers

Incoming properties

{
  "branch": "refs/heads/master",
  "repository": "https://chromium.googlesource.com/native_client/src/native_client.git",
  "revision": "db511ff7952658efc9bf67afa8dfdac9faba2f93"
}

Incoming tags

  • buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93
  • buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93
  • gitiles_ref:refs/heads/master

Debug log (UTC)

[13:19:06.083] New invocation is queued and will start shortly
[13:19:07.442] Starting the invocation (attempt 1)
[13:19:07.473] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:19:07.473] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:19:07.473] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9089419445419734448",
  "parameters_json": "{\"builder_name\":\"linux-64-validator-opt\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"db511ff7952658efc9bf67afa8dfdac9faba2f93\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-64-validator-opt",
    "scheduler_invocation_id:9089419445419734448",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93",
    "gitiles_ref:refs/heads/master"
  ]
}
[13:19:09.277] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547817547562846",
    "id": "8923989383205853088",
    "lease_expiration_ts": "1547968748796794",
    "lease_key": "2116224374",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"db511ff7952658efc9bf67afa8dfdac9faba2f93\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1547817548980800",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/96",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089419445419734448",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_dimension:caches:builder_ec3024801e8fae9a0c90cf0b91bc8fd309d619937749998ead917e6c37a3a5b5_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.flex.ci",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.ci/linux-64-validator-opt/96",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8923989383205853088",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "swarming_tag:caches:builder_ec3024801e8fae9a0c90cf0b91bc8fd309d619937749998ead917e6c37a3a5b5_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8923989383205853088/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.flex.ci",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089419445419734448",
      "swarming_tag:scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_tag:service_account:nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4279ef01b5c80e10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547817548980901",
    "url": "https://ci.chromium.org/b/8923989383205853088",
    "utcnow_ts": "1547817549224847"
  }
}
[13:19:09.277] Task URL: https://ci.chromium.org/b/8923989383205853088
[13:19:09.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:2:0) after 1m0s
[13:19:40.445] Received PubSub notification, asking Buildbucket for the build status
[13:19:40.514] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:09.291] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:2:0)
[13:20:09.291] Timer tick, asking Buildbucket for the build status
[13:20:09.342] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:09.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:4:0) after 1m0s
[13:21:09.360] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:4:0)
[13:21:09.360] Timer tick, asking Buildbucket for the build status
[13:21:09.482] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:09.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:5:0) after 1m0s
[13:22:09.510] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:5:0)
[13:22:09.510] Timer tick, asking Buildbucket for the build status
[13:22:09.642] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:09.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:6:0) after 1m0s
[13:23:09.657] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:6:0)
[13:23:09.657] Timer tick, asking Buildbucket for the build status
[13:23:09.854] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:09.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:7:0) after 1m0s
[13:24:09.870] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:7:0)
[13:24:09.870] Timer tick, asking Buildbucket for the build status
[13:24:09.963] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:09.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:8:0) after 1m0s
[13:25:09.979] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:8:0)
[13:25:09.979] Timer tick, asking Buildbucket for the build status
[13:25:10.018] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:10.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:9:0) after 1m0s
[13:26:10.038] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:9:0)
[13:26:10.038] Timer tick, asking Buildbucket for the build status
[13:26:10.110] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:10.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:10:0) after 1m0s
[13:27:10.125] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:10:0)
[13:27:10.125] Timer tick, asking Buildbucket for the build status
[13:27:10.167] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:10.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:11:0) after 1m0s
[13:28:10.188] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:11:0)
[13:28:10.188] Timer tick, asking Buildbucket for the build status
[13:28:10.268] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:10.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:12:0) after 1m0s
[13:29:10.337] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:12:0)
[13:29:10.337] Timer tick, asking Buildbucket for the build status
[13:29:10.371] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:10.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:13:0) after 1m0s
[13:30:10.388] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:13:0)
[13:30:10.388] Timer tick, asking Buildbucket for the build status
[13:30:10.435] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:10.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:14:0) after 1m0s
[13:31:10.459] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:14:0)
[13:31:10.459] Timer tick, asking Buildbucket for the build status
[13:31:10.519] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:10.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:15:0) after 1m0s
[13:32:10.537] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:15:0)
[13:32:10.537] Timer tick, asking Buildbucket for the build status
[13:32:10.595] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:10.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:16:0) after 1m0s
[13:33:10.612] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:16:0)
[13:33:10.612] Timer tick, asking Buildbucket for the build status
[13:33:10.729] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:10.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:17:0) after 1m0s
[13:34:10.810] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:17:0)
[13:34:10.810] Timer tick, asking Buildbucket for the build status
[13:34:11.239] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:11.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:18:0) after 1m0s
[13:35:11.313] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:18:0)
[13:35:11.313] Timer tick, asking Buildbucket for the build status
[13:35:11.371] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:11.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:19:0) after 1m0s
[13:36:11.388] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:19:0)
[13:36:11.388] Timer tick, asking Buildbucket for the build status
[13:36:11.550] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:11.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:20:0) after 1m0s
[13:37:11.568] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:20:0)
[13:37:11.568] Timer tick, asking Buildbucket for the build status
[13:37:11.612] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:11.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:21:0) after 1m0s
[13:38:11.656] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:21:0)
[13:38:11.656] Timer tick, asking Buildbucket for the build status
[13:38:11.811] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:11.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:22:0) after 1m0s
[13:39:11.830] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:22:0)
[13:39:11.830] Timer tick, asking Buildbucket for the build status
[13:39:11.874] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:11.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:23:0) after 1m0s
[13:40:11.897] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:23:0)
[13:40:11.897] Timer tick, asking Buildbucket for the build status
[13:40:12.403] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:12.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:24:0) after 1m0s
[13:41:12.431] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:24:0)
[13:41:12.431] Timer tick, asking Buildbucket for the build status
[13:41:13.108] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:13.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:25:0) after 1m0s
[13:42:13.125] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:25:0)
[13:42:13.125] Timer tick, asking Buildbucket for the build status
[13:42:13.189] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:13.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:26:0) after 1m0s
[13:43:13.210] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:26:0)
[13:43:13.210] Timer tick, asking Buildbucket for the build status
[13:43:13.317] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:13.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:27:0) after 1m0s
[13:44:13.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:27:0)
[13:44:13.384] Timer tick, asking Buildbucket for the build status
[13:44:13.541] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:13.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:28:0) after 1m0s
[13:45:13.644] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:28:0)
[13:45:13.644] Timer tick, asking Buildbucket for the build status
[13:45:13.695] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:13.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:29:0) after 1m0s
[13:46:13.714] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:29:0)
[13:46:13.714] Timer tick, asking Buildbucket for the build status
[13:46:13.762] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:13.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:30:0) after 1m0s
[13:47:13.789] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:30:0)
[13:47:13.789] Timer tick, asking Buildbucket for the build status
[13:47:14.030] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:14.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:31:0) after 1m0s
[13:48:14.073] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:31:0)
[13:48:14.073] Timer tick, asking Buildbucket for the build status
[13:48:14.156] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:14.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:32:0) after 1m0s
[13:49:14.188] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:32:0)
[13:49:14.188] Timer tick, asking Buildbucket for the build status
[13:49:14.253] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:14.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:33:0) after 1m0s
[13:50:14.274] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:33:0)
[13:50:14.274] Timer tick, asking Buildbucket for the build status
[13:50:14.348] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:14.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:34:0) after 1m0s
[13:51:14.366] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:34:0)
[13:51:14.366] Timer tick, asking Buildbucket for the build status
[13:51:14.491] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:14.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:35:0) after 1m0s
[13:52:14.507] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:35:0)
[13:52:14.507] Timer tick, asking Buildbucket for the build status
[13:52:14.647] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:14.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:36:0) after 1m0s
[13:53:14.666] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:36:0)
[13:53:14.666] Timer tick, asking Buildbucket for the build status
[13:53:14.733] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:14.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:37:0) after 1m0s
[13:54:14.749] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:37:0)
[13:54:14.749] Timer tick, asking Buildbucket for the build status
[13:54:14.879] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:14.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:38:0) after 1m0s
[13:55:14.903] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:38:0)
[13:55:14.903] Timer tick, asking Buildbucket for the build status
[13:55:14.961] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:14.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:39:0) after 1m0s
[13:56:14.980] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:39:0)
[13:56:14.980] Timer tick, asking Buildbucket for the build status
[13:56:15.022] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:15.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:40:0) after 1m0s
[13:57:15.043] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:40:0)
[13:57:15.043] Timer tick, asking Buildbucket for the build status
[13:57:15.113] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:15.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:41:0) after 1m0s
[13:58:15.189] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:41:0)
[13:58:15.189] Timer tick, asking Buildbucket for the build status
[13:58:15.241] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:15.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:42:0) after 1m0s
[13:59:15.260] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:42:0)
[13:59:15.260] Timer tick, asking Buildbucket for the build status
[13:59:15.519] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:15.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:43:0) after 1m0s
[14:00:15.599] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:43:0)
[14:00:15.599] Timer tick, asking Buildbucket for the build status
[14:00:15.661] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:15.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:44:0) after 1m0s
[14:01:15.709] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:44:0)
[14:01:15.709] Timer tick, asking Buildbucket for the build status
[14:01:15.933] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:15.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:45:0) after 1m0s
[14:02:15.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:45:0)
[14:02:15.981] Timer tick, asking Buildbucket for the build status
[14:02:16.112] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:16.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:46:0) after 1m0s
[14:03:16.144] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:46:0)
[14:03:16.144] Timer tick, asking Buildbucket for the build status
[14:03:16.230] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:16.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:47:0) after 1m0s
[14:04:16.266] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:47:0)
[14:04:16.266] Timer tick, asking Buildbucket for the build status
[14:04:16.319] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:16.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:48:0) after 1m0s
[14:05:16.338] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:48:0)
[14:05:16.338] Timer tick, asking Buildbucket for the build status
[14:05:16.417] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:16.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:49:0) after 1m0s
[14:06:16.438] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:49:0)
[14:06:16.438] Timer tick, asking Buildbucket for the build status
[14:06:16.658] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:16.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:50:0) after 1m0s
[14:07:16.734] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:50:0)
[14:07:16.734] Timer tick, asking Buildbucket for the build status
[14:07:17.024] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:17.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:51:0) after 1m0s
[14:08:17.051] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:51:0)
[14:08:17.051] Timer tick, asking Buildbucket for the build status
[14:08:17.120] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:17.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:52:0) after 1m0s
[14:09:17.138] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:52:0)
[14:09:17.138] Timer tick, asking Buildbucket for the build status
[14:09:17.264] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:17.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:53:0) after 1m0s
[14:10:17.283] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:53:0)
[14:10:17.283] Timer tick, asking Buildbucket for the build status
[14:10:17.358] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:17.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:54:0) after 1m0s
[14:11:17.387] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:54:0)
[14:11:17.387] Timer tick, asking Buildbucket for the build status
[14:11:17.506] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:17.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:55:0) after 1m0s
[14:12:17.523] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:55:0)
[14:12:17.523] Timer tick, asking Buildbucket for the build status
[14:12:17.572] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:17.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:56:0) after 1m0s
[14:13:17.739] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:56:0)
[14:13:17.739] Timer tick, asking Buildbucket for the build status
[14:13:17.805] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:17.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:57:0) after 1m0s
[14:14:17.828] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:57:0)
[14:14:17.828] Timer tick, asking Buildbucket for the build status
[14:14:17.948] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:17.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:58:0) after 1m0s
[14:15:17.982] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:58:0)
[14:15:17.982] Timer tick, asking Buildbucket for the build status
[14:15:18.134] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:18.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:59:0) after 1m0s
[14:16:18.164] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:59:0)
[14:16:18.164] Timer tick, asking Buildbucket for the build status
[14:16:18.422] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:18.422] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:60:0) after 1m0s
[14:17:18.438] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:60:0)
[14:17:18.438] Timer tick, asking Buildbucket for the build status
[14:17:18.474] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:18.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:61:0) after 1m0s
[14:18:18.497] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:61:0)
[14:18:18.497] Timer tick, asking Buildbucket for the build status
[14:18:18.658] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:18.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:62:0) after 1m0s
[14:19:18.738] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:62:0)
[14:19:18.738] Timer tick, asking Buildbucket for the build status
[14:19:18.788] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:18.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:63:0) after 1m0s
[14:20:18.806] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:63:0)
[14:20:18.806] Timer tick, asking Buildbucket for the build status
[14:20:18.844] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:18.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:64:0) after 1m0s
[14:21:18.862] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:64:0)
[14:21:18.862] Timer tick, asking Buildbucket for the build status
[14:21:18.957] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:18.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:65:0) after 1m0s
[14:22:18.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:65:0)
[14:22:18.981] Timer tick, asking Buildbucket for the build status
[14:22:19.055] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:19.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:66:0) after 1m0s
[14:23:19.099] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:66:0)
[14:23:19.099] Timer tick, asking Buildbucket for the build status
[14:23:19.216] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:19.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:67:0) after 1m0s
[14:24:19.232] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:67:0)
[14:24:19.232] Timer tick, asking Buildbucket for the build status
[14:24:19.334] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:19.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:68:0) after 1m0s
[14:25:19.355] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:68:0)
[14:25:19.355] Timer tick, asking Buildbucket for the build status
[14:25:19.421] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:19.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:69:0) after 1m0s
[14:26:19.443] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:69:0)
[14:26:19.443] Timer tick, asking Buildbucket for the build status
[14:26:19.524] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:19.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:70:0) after 1m0s
[14:27:19.591] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:70:0)
[14:27:19.591] Timer tick, asking Buildbucket for the build status
[14:27:19.809] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:19.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:71:0) after 1m0s
[14:28:19.831] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:71:0)
[14:28:19.831] Timer tick, asking Buildbucket for the build status
[14:28:19.894] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:19.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:72:0) after 1m0s
[14:29:19.911] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:72:0)
[14:29:19.911] Timer tick, asking Buildbucket for the build status
[14:29:20.026] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:20.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:73:0) after 1m0s
[14:30:20.044] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:73:0)
[14:30:20.044] Timer tick, asking Buildbucket for the build status
[14:30:20.099] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:20.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:74:0) after 1m0s
[14:31:20.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:74:0)
[14:31:20.117] Timer tick, asking Buildbucket for the build status
[14:31:20.191] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:20.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:75:0) after 1m0s
[14:32:20.229] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:75:0)
[14:32:20.229] Timer tick, asking Buildbucket for the build status
[14:32:20.467] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:20.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:76:0) after 1m0s
[14:33:20.498] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:76:0)
[14:33:20.498] Timer tick, asking Buildbucket for the build status
[14:33:20.574] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:20.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:77:0) after 1m0s
[14:34:20.618] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:77:0)
[14:34:20.618] Timer tick, asking Buildbucket for the build status
[14:34:20.702] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:20.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:78:0) after 1m0s
[14:35:20.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:78:0)
[14:35:20.722] Timer tick, asking Buildbucket for the build status
[14:35:20.768] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:20.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:79:0) after 1m0s
[14:36:20.788] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:79:0)
[14:36:20.788] Timer tick, asking Buildbucket for the build status
[14:36:20.863] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:20.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:80:0) after 1m0s
[14:37:20.882] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:80:0)
[14:37:20.882] Timer tick, asking Buildbucket for the build status
[14:37:20.956] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:20.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:81:0) after 1m0s
[14:38:20.984] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:81:0)
[14:38:20.984] Timer tick, asking Buildbucket for the build status
[14:38:21.136] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:21.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:82:0) after 1m0s
[14:39:21.156] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:82:0)
[14:39:21.156] Timer tick, asking Buildbucket for the build status
[14:39:21.351] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:21.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:83:0) after 1m0s
[14:40:21.381] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:83:0)
[14:40:21.381] Timer tick, asking Buildbucket for the build status
[14:40:21.550] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:21.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:84:0) after 1m0s
[14:41:21.623] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:84:0)
[14:41:21.623] Timer tick, asking Buildbucket for the build status
[14:41:21.849] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:21.849] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:85:0) after 1m0s
[14:42:21.874] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:85:0)
[14:42:21.874] Timer tick, asking Buildbucket for the build status
[14:42:21.927] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:21.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:86:0) after 1m0s
[14:43:21.951] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:86:0)
[14:43:21.951] Timer tick, asking Buildbucket for the build status
[14:43:22.002] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:22.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:87:0) after 1m0s
[14:44:22.019] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:87:0)
[14:44:22.019] Timer tick, asking Buildbucket for the build status
[14:44:22.180] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:22.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:88:0) after 1m0s
[14:45:22.198] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:88:0)
[14:45:22.198] Timer tick, asking Buildbucket for the build status
[14:45:22.257] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:22.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:89:0) after 1m0s
[14:46:22.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:89:0)
[14:46:22.325] Timer tick, asking Buildbucket for the build status
[14:46:22.385] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:22.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:90:0) after 1m0s
[14:47:22.411] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:90:0)
[14:47:22.411] Timer tick, asking Buildbucket for the build status
[14:47:22.487] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:22.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:91:0) after 1m0s
[14:48:22.496] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:91:0)
[14:48:22.496] Timer tick, asking Buildbucket for the build status
[14:48:22.775] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:22.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:92:0) after 1m0s
[14:49:22.805] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:92:0)
[14:49:22.805] Timer tick, asking Buildbucket for the build status
[14:49:22.932] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:22.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:93:0) after 1m0s
[14:50:22.963] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:93:0)
[14:50:22.963] Timer tick, asking Buildbucket for the build status
[14:50:23.061] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:23.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:94:0) after 1m0s
[14:51:23.089] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:94:0)
[14:51:23.089] Timer tick, asking Buildbucket for the build status
[14:51:23.179] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:23.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:95:0) after 1m0s
[14:52:23.230] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:95:0)
[14:52:23.230] Timer tick, asking Buildbucket for the build status
[14:52:23.635] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:23.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:96:0) after 1m0s
[14:53:23.664] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:96:0)
[14:53:23.664] Timer tick, asking Buildbucket for the build status
[14:53:23.786] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:23.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:97:0) after 1m0s
[14:54:23.807] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:97:0)
[14:54:23.807] Timer tick, asking Buildbucket for the build status
[14:54:23.858] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:23.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:98:0) after 1m0s
[14:55:23.881] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:98:0)
[14:55:23.881] Timer tick, asking Buildbucket for the build status
[14:55:23.952] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:23.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:99:0) after 1m0s
[14:56:23.973] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:99:0)
[14:56:23.973] Timer tick, asking Buildbucket for the build status
[14:56:24.021] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:24.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:100:0) after 1m0s
[14:57:24.047] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:100:0)
[14:57:24.047] Timer tick, asking Buildbucket for the build status
[14:57:24.244] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:24.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:101:0) after 1m0s
[14:58:24.294] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:101:0)
[14:58:24.294] Timer tick, asking Buildbucket for the build status
[14:58:24.342] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:24.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:102:0) after 1m0s
[14:59:24.365] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:102:0)
[14:59:24.365] Timer tick, asking Buildbucket for the build status
[14:59:24.414] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:24.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:103:0) after 1m0s
[15:00:24.459] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:103:0)
[15:00:24.459] Timer tick, asking Buildbucket for the build status
[15:00:24.541] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:24.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:104:0) after 1m0s
[15:01:24.558] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:104:0)
[15:01:24.558] Timer tick, asking Buildbucket for the build status
[15:01:24.850] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:24.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:105:0) after 1m0s
[15:02:24.870] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:105:0)
[15:02:24.870] Timer tick, asking Buildbucket for the build status
[15:02:25.773] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:25.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:106:0) after 1m0s
[15:03:25.792] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:106:0)
[15:03:25.792] Timer tick, asking Buildbucket for the build status
[15:03:25.877] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:25.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:107:0) after 1m0s
[15:04:25.899] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:107:0)
[15:04:25.899] Timer tick, asking Buildbucket for the build status
[15:04:25.970] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:25.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:108:0) after 1m0s
[15:05:25.989] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:108:0)
[15:05:25.989] Timer tick, asking Buildbucket for the build status
[15:05:26.107] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:26.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:109:0) after 1m0s
[15:06:26.127] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:109:0)
[15:06:26.127] Timer tick, asking Buildbucket for the build status
[15:06:26.302] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:26.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:110:0) after 1m0s
[15:07:26.327] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:110:0)
[15:07:26.327] Timer tick, asking Buildbucket for the build status
[15:07:27.155] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:27.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:111:0) after 1m0s
[15:08:27.181] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:111:0)
[15:08:27.181] Timer tick, asking Buildbucket for the build status
[15:08:27.257] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:27.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:112:0) after 1m0s
[15:09:27.286] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:112:0)
[15:09:27.286] Timer tick, asking Buildbucket for the build status
[15:09:27.361] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:27.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:113:0) after 1m0s
[15:10:27.385] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:113:0)
[15:10:27.385] Timer tick, asking Buildbucket for the build status
[15:10:27.616] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:27.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:114:0) after 1m0s
[15:11:27.635] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:114:0)
[15:11:27.635] Timer tick, asking Buildbucket for the build status
[15:11:27.830] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:27.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:115:0) after 1m0s
[15:12:27.897] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:115:0)
[15:12:27.897] Timer tick, asking Buildbucket for the build status
[15:12:28.072] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:28.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:116:0) after 1m0s
[15:13:28.102] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:116:0)
[15:13:28.102] Timer tick, asking Buildbucket for the build status
[15:13:28.171] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:28.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:117:0) after 1m0s
[15:14:28.243] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:117:0)
[15:14:28.243] Timer tick, asking Buildbucket for the build status
[15:14:28.812] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:28.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:118:0) after 1m0s
[15:15:28.832] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:118:0)
[15:15:28.832] Timer tick, asking Buildbucket for the build status
[15:15:28.995] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:28.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:119:0) after 1m0s
[15:16:29.018] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:119:0)
[15:16:29.018] Timer tick, asking Buildbucket for the build status
[15:16:29.119] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:29.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:120:0) after 1m0s
[15:17:29.139] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:120:0)
[15:17:29.139] Timer tick, asking Buildbucket for the build status
[15:17:29.553] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:29.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:121:0) after 1m0s
[15:18:29.573] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:121:0)
[15:18:29.573] Timer tick, asking Buildbucket for the build status
[15:18:29.630] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:29.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:122:0) after 1m0s
[15:19:29.649] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:122:0)
[15:19:29.649] Timer tick, asking Buildbucket for the build status
[15:19:29.757] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:29.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:123:0) after 1m0s
[15:20:29.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:123:0)
[15:20:29.786] Timer tick, asking Buildbucket for the build status
[15:20:29.851] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:29.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:124:0) after 1m0s
[15:21:29.871] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:124:0)
[15:21:29.871] Timer tick, asking Buildbucket for the build status
[15:21:30.002] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:30.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:125:0) after 1m0s
[15:22:30.023] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:125:0)
[15:22:30.023] Timer tick, asking Buildbucket for the build status
[15:22:30.114] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:30.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:126:0) after 1m0s
[15:23:30.138] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:126:0)
[15:23:30.138] Timer tick, asking Buildbucket for the build status
[15:23:30.210] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:30.210] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:127:0) after 1m0s
[15:24:30.229] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:127:0)
[15:24:30.229] Timer tick, asking Buildbucket for the build status
[15:24:30.287] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:30.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:128:0) after 1m0s
[15:25:30.307] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:128:0)
[15:25:30.307] Timer tick, asking Buildbucket for the build status
[15:25:30.350] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:30.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:129:0) after 1m0s
[15:26:30.458] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:129:0)
[15:26:30.458] Timer tick, asking Buildbucket for the build status
[15:26:30.604] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:30.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:130:0) after 1m0s
[15:27:30.630] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:130:0)
[15:27:30.630] Timer tick, asking Buildbucket for the build status
[15:27:30.755] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:30.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:131:0) after 1m0s
[15:28:30.775] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:131:0)
[15:28:30.775] Timer tick, asking Buildbucket for the build status
[15:28:30.858] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:30.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:132:0) after 1m0s
[15:29:30.881] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:132:0)
[15:29:30.881] Timer tick, asking Buildbucket for the build status
[15:29:31.209] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:31.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:133:0) after 1m0s
[15:30:31.234] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:133:0)
[15:30:31.234] Timer tick, asking Buildbucket for the build status
[15:30:31.308] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:31.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:134:0) after 1m0s
[15:31:31.299] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:134:0)
[15:31:31.299] Timer tick, asking Buildbucket for the build status
[15:31:31.947] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:31.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:135:0) after 1m0s
[15:32:31.971] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:135:0)
[15:32:31.971] Timer tick, asking Buildbucket for the build status
[15:32:32.083] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:32.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:136:0) after 1m0s
[15:33:32.106] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:136:0)
[15:33:32.106] Timer tick, asking Buildbucket for the build status
[15:33:32.444] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:32.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:137:0) after 1m0s
[15:34:32.472] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:137:0)
[15:34:32.473] Timer tick, asking Buildbucket for the build status
[15:34:32.725] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:32.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:138:0) after 1m0s
[15:35:32.747] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:138:0)
[15:35:32.747] Timer tick, asking Buildbucket for the build status
[15:35:32.945] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:32.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:139:0) after 1m0s
[15:36:32.965] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:139:0)
[15:36:32.965] Timer tick, asking Buildbucket for the build status
[15:36:33.046] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:33.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:140:0) after 1m0s
[15:37:33.072] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:140:0)
[15:37:33.072] Timer tick, asking Buildbucket for the build status
[15:37:33.120] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:33.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:141:0) after 1m0s
[15:38:33.144] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:141:0)
[15:38:33.144] Timer tick, asking Buildbucket for the build status
[15:38:33.464] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:33.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:142:0) after 1m0s
[15:39:33.486] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:142:0)
[15:39:33.486] Timer tick, asking Buildbucket for the build status
[15:39:33.682] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:33.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:143:0) after 1m0s
[15:40:33.705] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:143:0)
[15:40:33.705] Timer tick, asking Buildbucket for the build status
[15:40:33.986] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:33.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:144:0) after 1m0s
[15:41:34.013] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:144:0)
[15:41:34.013] Timer tick, asking Buildbucket for the build status
[15:41:34.104] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:34.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:145:0) after 1m0s
[15:42:34.134] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:145:0)
[15:42:34.134] Timer tick, asking Buildbucket for the build status
[15:42:34.298] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:34.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:146:0) after 1m0s
[15:43:34.381] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:146:0)
[15:43:34.381] Timer tick, asking Buildbucket for the build status
[15:43:34.657] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:34.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:147:0) after 1m0s
[15:44:34.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:147:0)
[15:44:34.679] Timer tick, asking Buildbucket for the build status
[15:44:35.034] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:35.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:148:0) after 1m0s
[15:45:35.053] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:148:0)
[15:45:35.053] Timer tick, asking Buildbucket for the build status
[15:45:35.464] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:35.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:149:0) after 1m0s
[15:46:35.497] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:149:0)
[15:46:35.497] Timer tick, asking Buildbucket for the build status
[15:46:35.609] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:35.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:150:0) after 1m0s
[15:47:35.629] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:150:0)
[15:47:35.629] Timer tick, asking Buildbucket for the build status
[15:47:36.016] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:36.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:151:0) after 1m0s
[15:48:36.114] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:151:0)
[15:48:36.115] Timer tick, asking Buildbucket for the build status
[15:48:36.329] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:36.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:152:0) after 1m0s
[15:49:36.348] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:152:0)
[15:49:36.348] Timer tick, asking Buildbucket for the build status
[15:49:36.412] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:36.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:153:0) after 1m0s
[15:50:36.440] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:153:0)
[15:50:36.440] Timer tick, asking Buildbucket for the build status
[15:50:36.735] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:36.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:154:0) after 1m0s
[15:51:36.755] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:154:0)
[15:51:36.755] Timer tick, asking Buildbucket for the build status
[15:51:36.811] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:36.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:155:0) after 1m0s
[15:52:36.834] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:155:0)
[15:52:36.834] Timer tick, asking Buildbucket for the build status
[15:52:36.927] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:36.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:156:0) after 1m0s
[15:53:36.949] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:156:0)
[15:53:36.949] Timer tick, asking Buildbucket for the build status
[15:53:37.034] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:37.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:157:0) after 1m0s
[15:54:37.059] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:157:0)
[15:54:37.059] Timer tick, asking Buildbucket for the build status
[15:54:37.127] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:37.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:158:0) after 1m0s
[15:55:37.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:158:0)
[15:55:37.157] Timer tick, asking Buildbucket for the build status
[15:55:37.208] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:37.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:159:0) after 1m0s
[15:56:37.233] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:159:0)
[15:56:37.233] Timer tick, asking Buildbucket for the build status
[15:56:37.342] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:37.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:160:0) after 1m0s
[15:57:37.373] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:160:0)
[15:57:37.373] Timer tick, asking Buildbucket for the build status
[15:57:37.428] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:37.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:161:0) after 1m0s
[15:58:37.448] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:161:0)
[15:58:37.448] Timer tick, asking Buildbucket for the build status
[15:58:37.670] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:37.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:162:0) after 1m0s
[15:59:37.692] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:162:0)
[15:59:37.692] Timer tick, asking Buildbucket for the build status
[15:59:37.769] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:37.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:163:0) after 1m0s
[16:00:37.797] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:163:0)
[16:00:37.797] Timer tick, asking Buildbucket for the build status
[16:00:37.845] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:37.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:164:0) after 1m0s
[16:01:37.866] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:164:0)
[16:01:37.866] Timer tick, asking Buildbucket for the build status
[16:01:37.938] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:37.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:165:0) after 1m0s
[16:02:37.982] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:165:0)
[16:02:37.982] Timer tick, asking Buildbucket for the build status
[16:02:38.070] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:38.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:166:0) after 1m0s
[16:03:38.095] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:166:0)
[16:03:38.095] Timer tick, asking Buildbucket for the build status
[16:03:38.184] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:38.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:167:0) after 1m0s
[16:04:38.241] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:167:0)
[16:04:38.241] Timer tick, asking Buildbucket for the build status
[16:04:38.306] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:38.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:168:0) after 1m0s
[16:05:38.355] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:168:0)
[16:05:38.355] Timer tick, asking Buildbucket for the build status
[16:05:38.482] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:38.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:169:0) after 1m0s
[16:06:38.510] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:169:0)
[16:06:38.510] Timer tick, asking Buildbucket for the build status
[16:06:38.583] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:38.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:170:0) after 1m0s
[16:07:38.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:170:0)
[16:07:38.606] Timer tick, asking Buildbucket for the build status
[16:07:38.841] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:38.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:171:0) after 1m0s
[16:08:38.865] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:171:0)
[16:08:38.865] Timer tick, asking Buildbucket for the build status
[16:08:38.968] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:38.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:172:0) after 1m0s
[16:09:38.992] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:172:0)
[16:09:38.992] Timer tick, asking Buildbucket for the build status
[16:09:39.046] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:39.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:173:0) after 1m0s
[16:10:39.074] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:173:0)
[16:10:39.074] Timer tick, asking Buildbucket for the build status
[16:10:39.158] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:39.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:174:0) after 1m0s
[16:11:39.185] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:174:0)
[16:11:39.185] Timer tick, asking Buildbucket for the build status
[16:11:39.292] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:39.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:175:0) after 1m0s
[16:12:39.321] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:175:0)
[16:12:39.321] Timer tick, asking Buildbucket for the build status
[16:12:39.425] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:39.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:176:0) after 1m0s
[16:13:39.445] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:176:0)
[16:13:39.445] Timer tick, asking Buildbucket for the build status
[16:13:39.621] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:39.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:177:0) after 1m0s
[16:14:39.645] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:177:0)
[16:14:39.645] Timer tick, asking Buildbucket for the build status
[16:14:39.872] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:39.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:178:0) after 1m0s
[16:15:39.894] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:178:0)
[16:15:39.894] Timer tick, asking Buildbucket for the build status
[16:15:40.469] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:40.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:179:0) after 1m0s
[16:16:40.494] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:179:0)
[16:16:40.494] Timer tick, asking Buildbucket for the build status
[16:16:40.949] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:40.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:180:0) after 1m0s
[16:17:40.970] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:180:0)
[16:17:40.970] Timer tick, asking Buildbucket for the build status
[16:17:41.058] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:41.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:181:0) after 1m0s
[16:18:41.081] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:181:0)
[16:18:41.081] Timer tick, asking Buildbucket for the build status
[16:18:41.213] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:41.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:182:0) after 1m0s
[16:19:41.233] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:182:0)
[16:19:41.233] Timer tick, asking Buildbucket for the build status
[16:19:41.498] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:41.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:183:0) after 1m0s
[16:20:41.521] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:183:0)
[16:20:41.521] Timer tick, asking Buildbucket for the build status
[16:20:41.639] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:41.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:184:0) after 1m0s
[16:21:41.663] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:184:0)
[16:21:41.663] Timer tick, asking Buildbucket for the build status
[16:21:41.917] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:41.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:185:0) after 1m0s
[16:22:41.940] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:185:0)
[16:22:41.940] Timer tick, asking Buildbucket for the build status
[16:22:42.010] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:42.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:186:0) after 1m0s
[16:23:42.049] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:186:0)
[16:23:42.049] Timer tick, asking Buildbucket for the build status
[16:23:42.244] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:42.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:187:0) after 1m0s
[16:24:42.274] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:187:0)
[16:24:42.274] Timer tick, asking Buildbucket for the build status
[16:24:42.355] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:42.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:188:0) after 1m0s
[16:25:42.388] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:188:0)
[16:25:42.388] Timer tick, asking Buildbucket for the build status
[16:25:42.497] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:42.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:189:0) after 1m0s
[16:26:42.519] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:189:0)
[16:26:42.519] Timer tick, asking Buildbucket for the build status
[16:26:42.738] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:42.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:190:0) after 1m0s
[16:27:42.760] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:190:0)
[16:27:42.760] Timer tick, asking Buildbucket for the build status
[16:27:42.833] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:42.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:191:0) after 1m0s
[16:28:42.867] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:191:0)
[16:28:42.867] Timer tick, asking Buildbucket for the build status
[16:28:43.719] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:43.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:192:0) after 1m0s
[16:29:43.795] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:192:0)
[16:29:43.795] Timer tick, asking Buildbucket for the build status
[16:29:43.856] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:43.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:193:0) after 1m0s
[16:30:43.906] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:193:0)
[16:30:43.906] Timer tick, asking Buildbucket for the build status
[16:30:43.996] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:43.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:194:0) after 1m0s
[16:31:44.042] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:194:0)
[16:31:44.042] Timer tick, asking Buildbucket for the build status
[16:31:44.447] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:44.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:195:0) after 1m0s
[16:32:44.486] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:195:0)
[16:32:44.486] Timer tick, asking Buildbucket for the build status
[16:32:44.607] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:44.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:196:0) after 1m0s
[16:33:44.635] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:196:0)
[16:33:44.635] Timer tick, asking Buildbucket for the build status
[16:33:44.817] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:44.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:197:0) after 1m0s
[16:34:44.839] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:197:0)
[16:34:44.839] Timer tick, asking Buildbucket for the build status
[16:34:45.164] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:45.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:198:0) after 1m0s
[16:35:45.188] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:198:0)
[16:35:45.188] Timer tick, asking Buildbucket for the build status
[16:35:45.821] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:45.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:199:0) after 1m0s
[16:36:45.852] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:199:0)
[16:36:45.852] Timer tick, asking Buildbucket for the build status
[16:36:45.952] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:45.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:200:0) after 1m0s
[16:37:45.975] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:200:0)
[16:37:45.975] Timer tick, asking Buildbucket for the build status
[16:37:46.078] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:46.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:201:0) after 1m0s
[16:38:46.115] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:201:0)
[16:38:46.115] Timer tick, asking Buildbucket for the build status
[16:38:46.167] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:46.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:202:0) after 1m0s
[16:39:46.219] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:202:0)
[16:39:46.219] Timer tick, asking Buildbucket for the build status
[16:39:46.967] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:46.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:203:0) after 1m0s
[16:40:47.046] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:203:0)
[16:40:47.046] Timer tick, asking Buildbucket for the build status
[16:40:47.594] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:47.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:204:0) after 1m0s
[16:41:47.618] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:204:0)
[16:41:47.618] Timer tick, asking Buildbucket for the build status
[16:41:47.687] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:47.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:205:0) after 1m0s
[16:42:47.712] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:205:0)
[16:42:47.712] Timer tick, asking Buildbucket for the build status
[16:42:47.774] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:47.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:206:0) after 1m0s
[16:43:47.765] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:206:0)
[16:43:47.765] Timer tick, asking Buildbucket for the build status
[16:43:47.881] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:47.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:207:0) after 1m0s
[16:44:47.905] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:207:0)
[16:44:47.905] Timer tick, asking Buildbucket for the build status
[16:44:47.961] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:47.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:208:0) after 1m0s
[16:45:48.069] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:208:0)
[16:45:48.069] Timer tick, asking Buildbucket for the build status
[16:45:48.142] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:48.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:209:0) after 1m0s
[16:46:48.196] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:209:0)
[16:46:48.197] Timer tick, asking Buildbucket for the build status
[16:46:48.320] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:48.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:210:0) after 1m0s
[16:47:48.350] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:210:0)
[16:47:48.350] Timer tick, asking Buildbucket for the build status
[16:47:48.424] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:48.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:211:0) after 1m0s
[16:48:48.476] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:211:0)
[16:48:48.476] Timer tick, asking Buildbucket for the build status
[16:48:48.641] Build 8923989383205853088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:48.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089419445419734448:212:0) after 1m0s
[16:49:00.078] Received PubSub notification, asking Buildbucket for the build status
[16:49:00.122] Build 8923989383205853088: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:49:00.122] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1547830132331596",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547817547562846",
    "failure_reason": "BUILD_FAILURE",
    "id": "8923989383205853088",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"db511ff7952658efc9bf67afa8dfdac9faba2f93\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure predownload validator corpus\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4004-c99644c\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-canary-e973df7c-us-central1-c-shbw\"], \"pool\": [\"luci.flex.ci\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 96, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"db511ff7952658efc9bf67afa8dfdac9faba2f93\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547817547562846, \"tags\": [\"builder:linux-64-validator-opt\", \"buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089419445419734448\", \"scheduler_job_id:nacl/linux-64-validator-opt\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8923989383205853088\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"db511ff7952658efc9bf67afa8dfdac9faba2f93\", \"bot_id\": \"gce-trusty-canary-e973df7c-us-central1-c-shbw\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1547817579594375",
    "status": "COMPLETED",
    "status_changed_ts": "1547830139107499",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/96",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089419445419734448",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_dimension:caches:builder_ec3024801e8fae9a0c90cf0b91bc8fd309d619937749998ead917e6c37a3a5b5_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.flex.ci",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.ci/linux-64-validator-opt/96",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8923989383205853088",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db511ff7952658efc9bf67afa8dfdac9faba2f93",
      "swarming_tag:caches:builder_ec3024801e8fae9a0c90cf0b91bc8fd309d619937749998ead917e6c37a3a5b5_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8923989383205853088/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.flex.ci",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089419445419734448",
      "swarming_tag:scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_tag:service_account:nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4279ef01b5c80e10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547830139112129",
    "url": "https://ci.chromium.org/b/8923989383205853088",
    "utcnow_ts": "1547830140109910"
  }
}
[16:49:00.122] Invocation finished in 3h29m54.05538391s with status FAILED