LUCI Scheduler :: nacl :: linux-64-validator-opt

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

f96a677d7cb651e3772eeae2f665077a7494b185 (6 years ago)
14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a (6 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[03:50:12.734] New invocation is queued and will start shortly
[03:50:14.064] Starting the invocation (attempt 1)
[03:50:14.103] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:50:14.103] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:50:14.103] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9093169712454127072",
  "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\":\"14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a\"}}",
  "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:9093169712454127072",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
    "gitiles_ref:refs/heads/master"
  ]
}
[03:50:15.623] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary": true,
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1544241014214048",
    "id": "8927739650237816880",
    "lease_expiration_ts": "1544359815247578",
    "lease_key": "569765873",
    "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\": \"14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1544241015530610",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/82",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9093169712454127072",
      "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/82",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8927739650237816880",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:1",
      "swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "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/8927739650237816880/+/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:9093169712454127072",
      "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:eb0b43081c973a89e3df43ecc3d19a08676f42b3",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:41a4c16ff1cd8710",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1544241015530710",
    "url": "https://ci.chromium.org/b/8927739650237816880",
    "utcnow_ts": "1544241015610584"
  }
}
[03:50:15.623] Task URL: https://ci.chromium.org/b/8927739650237816880
[03:50:15.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:2:0) after 1m0s
[03:50:17.947] Received PubSub notification, asking Buildbucket for the build status
[03:50:18.058] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:15.643] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:2:0)
[03:51:15.643] Timer tick, asking Buildbucket for the build status
[03:51:15.839] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:15.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:4:0) after 1m0s
[03:52:15.886] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:4:0)
[03:52:15.886] Timer tick, asking Buildbucket for the build status
[03:52:15.943] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:15.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:5:0) after 1m0s
[03:53:15.959] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:5:0)
[03:53:15.959] Timer tick, asking Buildbucket for the build status
[03:53:16.084] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:16.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:6:0) after 1m0s
[03:54:16.112] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:6:0)
[03:54:16.112] Timer tick, asking Buildbucket for the build status
[03:54:16.222] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:16.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:7:0) after 1m0s
[03:55:16.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:7:0)
[03:55:16.242] Timer tick, asking Buildbucket for the build status
[03:55:16.309] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:16.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:8:0) after 1m0s
[03:56:16.324] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:8:0)
[03:56:16.324] Timer tick, asking Buildbucket for the build status
[03:56:16.393] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:16.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:9:0) after 1m0s
[03:57:16.411] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:9:0)
[03:57:16.411] Timer tick, asking Buildbucket for the build status
[03:57:16.520] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:16.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:10:0) after 1m0s
[03:58:16.534] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:10:0)
[03:58:16.534] Timer tick, asking Buildbucket for the build status
[03:58:16.573] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:16.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:11:0) after 1m0s
[03:59:16.588] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:11:0)
[03:59:16.588] Timer tick, asking Buildbucket for the build status
[03:59:16.669] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:16.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:12:0) after 1m0s
[04:00:16.686] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:12:0)
[04:00:16.686] Timer tick, asking Buildbucket for the build status
[04:00:16.759] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:16.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:13:0) after 1m0s
[04:01:16.775] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:13:0)
[04:01:16.775] Timer tick, asking Buildbucket for the build status
[04:01:16.842] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:16.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:14:0) after 1m0s
[04:02:16.858] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:14:0)
[04:02:16.858] Timer tick, asking Buildbucket for the build status
[04:02:16.932] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:16.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:15:0) after 1m0s
[04:03:16.959] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:15:0)
[04:03:16.959] Timer tick, asking Buildbucket for the build status
[04:03:17.012] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:17.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:16:0) after 1m0s
[04:04:17.032] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:16:0)
[04:04:17.032] Timer tick, asking Buildbucket for the build status
[04:04:17.338] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:17.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:17:0) after 1m0s
[04:05:17.369] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:17:0)
[04:05:17.369] Timer tick, asking Buildbucket for the build status
[04:05:17.423] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:17.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:18:0) after 1m0s
[04:06:17.439] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:18:0)
[04:06:17.439] Timer tick, asking Buildbucket for the build status
[04:06:17.490] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:17.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:19:0) after 1m0s
[04:07:17.506] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:19:0)
[04:07:17.506] Timer tick, asking Buildbucket for the build status
[04:07:17.590] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:17.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:20:0) after 1m0s
[04:08:17.638] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:20:0)
[04:08:17.638] Timer tick, asking Buildbucket for the build status
[04:08:17.696] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:17.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:21:0) after 1m0s
[04:09:17.760] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:21:0)
[04:09:17.760] Timer tick, asking Buildbucket for the build status
[04:09:17.797] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:17.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:22:0) after 1m0s
[04:10:17.942] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:22:0)
[04:10:17.942] Timer tick, asking Buildbucket for the build status
[04:10:18.026] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:18.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:23:0) after 1m0s
[04:11:18.067] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:23:0)
[04:11:18.067] Timer tick, asking Buildbucket for the build status
[04:11:18.123] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:18.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:24:0) after 1m0s
[04:12:18.141] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:24:0)
[04:12:18.141] Timer tick, asking Buildbucket for the build status
[04:12:18.241] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:18.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:25:0) after 1m0s
[04:13:18.273] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:25:0)
[04:13:18.273] Timer tick, asking Buildbucket for the build status
[04:13:18.353] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:18.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:26:0) after 1m0s
[04:14:18.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:26:0)
[04:14:18.384] Timer tick, asking Buildbucket for the build status
[04:14:18.456] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:18.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:27:0) after 1m0s
[04:15:18.472] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:27:0)
[04:15:18.472] Timer tick, asking Buildbucket for the build status
[04:15:18.531] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:18.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:28:0) after 1m0s
[04:16:18.664] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:28:0)
[04:16:18.664] Timer tick, asking Buildbucket for the build status
[04:16:18.830] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:18.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:29:0) after 1m0s
[04:17:18.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:29:0)
[04:17:18.967] Timer tick, asking Buildbucket for the build status
[04:17:19.007] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:19.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:30:0) after 1m0s
[04:18:19.065] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:30:0)
[04:18:19.065] Timer tick, asking Buildbucket for the build status
[04:18:19.125] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:19.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:31:0) after 1m0s
[04:19:19.148] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:31:0)
[04:19:19.148] Timer tick, asking Buildbucket for the build status
[04:19:19.183] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:19.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:32:0) after 1m0s
[04:20:19.204] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:32:0)
[04:20:19.204] Timer tick, asking Buildbucket for the build status
[04:20:19.330] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:19.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:33:0) after 1m0s
[04:21:19.345] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:33:0)
[04:21:19.345] Timer tick, asking Buildbucket for the build status
[04:21:19.528] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:19.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:34:0) after 1m0s
[04:22:19.549] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:34:0)
[04:22:19.549] Timer tick, asking Buildbucket for the build status
[04:22:19.625] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:19.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:35:0) after 1m0s
[04:23:19.646] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:35:0)
[04:23:19.647] Timer tick, asking Buildbucket for the build status
[04:23:19.708] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:19.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:36:0) after 1m0s
[04:24:19.733] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:36:0)
[04:24:19.733] Timer tick, asking Buildbucket for the build status
[04:24:19.818] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:19.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:37:0) after 1m0s
[04:25:19.832] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:37:0)
[04:25:19.832] Timer tick, asking Buildbucket for the build status
[04:25:19.923] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:19.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:38:0) after 1m0s
[04:26:19.941] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:38:0)
[04:26:19.941] Timer tick, asking Buildbucket for the build status
[04:26:20.133] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:20.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:39:0) after 1m0s
[04:27:20.151] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:39:0)
[04:27:20.151] Timer tick, asking Buildbucket for the build status
[04:27:20.217] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:20.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:40:0) after 1m0s
[04:28:20.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:40:0)
[04:28:20.242] Timer tick, asking Buildbucket for the build status
[04:28:20.321] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:20.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:41:0) after 1m0s
[04:29:20.346] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:41:0)
[04:29:20.346] Timer tick, asking Buildbucket for the build status
[04:29:20.418] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:20.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:42:0) after 1m0s
[04:30:20.444] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:42:0)
[04:30:20.444] Timer tick, asking Buildbucket for the build status
[04:30:20.601] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:20.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:43:0) after 1m0s
[04:31:20.624] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:43:0)
[04:31:20.624] Timer tick, asking Buildbucket for the build status
[04:31:20.738] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:20.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:44:0) after 1m0s
[04:32:20.759] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:44:0)
[04:32:20.759] Timer tick, asking Buildbucket for the build status
[04:32:20.947] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:20.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:45:0) after 1m0s
[04:33:20.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:45:0)
[04:33:20.967] Timer tick, asking Buildbucket for the build status
[04:33:21.133] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:21.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:46:0) after 1m0s
[04:34:21.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:46:0)
[04:34:21.153] Timer tick, asking Buildbucket for the build status
[04:34:21.250] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:21.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:47:0) after 1m0s
[04:35:21.267] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:47:0)
[04:35:21.267] Timer tick, asking Buildbucket for the build status
[04:35:21.357] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:21.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:48:0) after 1m0s
[04:36:21.375] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:48:0)
[04:36:21.375] Timer tick, asking Buildbucket for the build status
[04:36:21.420] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:21.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:49:0) after 1m0s
[04:37:21.441] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:49:0)
[04:37:21.441] Timer tick, asking Buildbucket for the build status
[04:37:21.694] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:21.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:50:0) after 1m0s
[04:38:21.710] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:50:0)
[04:38:21.710] Timer tick, asking Buildbucket for the build status
[04:38:21.979] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:21.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:51:0) after 1m0s
[04:39:22.037] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:51:0)
[04:39:22.037] Timer tick, asking Buildbucket for the build status
[04:39:22.139] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:22.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:52:0) after 1m0s
[04:40:22.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:52:0)
[04:40:22.157] Timer tick, asking Buildbucket for the build status
[04:40:22.254] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:22.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:53:0) after 1m0s
[04:41:22.270] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:53:0)
[04:41:22.270] Timer tick, asking Buildbucket for the build status
[04:41:22.359] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:22.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:54:0) after 1m0s
[04:42:22.398] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:54:0)
[04:42:22.398] Timer tick, asking Buildbucket for the build status
[04:42:22.463] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:22.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:55:0) after 1m0s
[04:43:22.484] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:55:0)
[04:43:22.484] Timer tick, asking Buildbucket for the build status
[04:43:22.525] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:22.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:56:0) after 1m0s
[04:44:22.547] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:56:0)
[04:44:22.547] Timer tick, asking Buildbucket for the build status
[04:44:22.639] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:22.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:57:0) after 1m0s
[04:45:22.662] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:57:0)
[04:45:22.662] Timer tick, asking Buildbucket for the build status
[04:45:22.771] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:22.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:58:0) after 1m0s
[04:46:22.826] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:58:0)
[04:46:22.826] Timer tick, asking Buildbucket for the build status
[04:46:23.301] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:23.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:59:0) after 1m0s
[04:47:23.320] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:59:0)
[04:47:23.320] Timer tick, asking Buildbucket for the build status
[04:47:23.370] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:23.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:60:0) after 1m0s
[04:48:23.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:60:0)
[04:48:23.394] Timer tick, asking Buildbucket for the build status
[04:48:24.509] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:24.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:61:0) after 1m0s
[04:49:24.526] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:61:0)
[04:49:24.526] Timer tick, asking Buildbucket for the build status
[04:49:24.595] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:24.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:62:0) after 1m0s
[04:50:24.614] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:62:0)
[04:50:24.614] Timer tick, asking Buildbucket for the build status
[04:50:24.692] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:24.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:63:0) after 1m0s
[04:51:24.712] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:63:0)
[04:51:24.712] Timer tick, asking Buildbucket for the build status
[04:51:24.834] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:24.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:64:0) after 1m0s
[04:52:24.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:64:0)
[04:52:24.854] Timer tick, asking Buildbucket for the build status
[04:52:24.919] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:24.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:65:0) after 1m0s
[04:53:25.099] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:65:0)
[04:53:25.099] Timer tick, asking Buildbucket for the build status
[04:53:25.215] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:25.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:66:0) after 1m0s
[04:54:25.245] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:66:0)
[04:54:25.245] Timer tick, asking Buildbucket for the build status
[04:54:25.323] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:25.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:67:0) after 1m0s
[04:55:25.341] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:67:0)
[04:55:25.341] Timer tick, asking Buildbucket for the build status
[04:55:25.455] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:25.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:68:0) after 1m0s
[04:56:25.474] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:68:0)
[04:56:25.474] Timer tick, asking Buildbucket for the build status
[04:56:25.560] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:25.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:69:0) after 1m0s
[04:57:25.579] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:69:0)
[04:57:25.579] Timer tick, asking Buildbucket for the build status
[04:57:25.727] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:25.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:70:0) after 1m0s
[04:58:25.745] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:70:0)
[04:58:25.745] Timer tick, asking Buildbucket for the build status
[04:58:25.786] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:25.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:71:0) after 1m0s
[04:59:25.805] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:71:0)
[04:59:25.805] Timer tick, asking Buildbucket for the build status
[04:59:26.021] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:26.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:72:0) after 1m0s
[05:00:26.051] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:72:0)
[05:00:26.051] Timer tick, asking Buildbucket for the build status
[05:00:26.251] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:26.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:73:0) after 1m0s
[05:01:26.271] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:73:0)
[05:01:26.271] Timer tick, asking Buildbucket for the build status
[05:01:26.334] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:26.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:74:0) after 1m0s
[05:02:26.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:74:0)
[05:02:26.352] Timer tick, asking Buildbucket for the build status
[05:02:26.474] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:26.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:75:0) after 1m0s
[05:03:26.492] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:75:0)
[05:03:26.492] Timer tick, asking Buildbucket for the build status
[05:03:26.542] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:26.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:76:0) after 1m0s
[05:04:26.733] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:76:0)
[05:04:26.733] Timer tick, asking Buildbucket for the build status
[05:04:26.809] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:26.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:77:0) after 1m0s
[05:05:26.830] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:77:0)
[05:05:26.830] Timer tick, asking Buildbucket for the build status
[05:05:26.912] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:26.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:78:0) after 1m0s
[05:06:26.932] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:78:0)
[05:06:26.932] Timer tick, asking Buildbucket for the build status
[05:06:27.096] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:27.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:79:0) after 1m0s
[05:07:27.142] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:79:0)
[05:07:27.142] Timer tick, asking Buildbucket for the build status
[05:07:27.402] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:27.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:80:0) after 1m0s
[05:08:27.422] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:80:0)
[05:08:27.422] Timer tick, asking Buildbucket for the build status
[05:08:27.471] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:27.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:81:0) after 1m0s
[05:09:27.505] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:81:0)
[05:09:27.505] Timer tick, asking Buildbucket for the build status
[05:09:27.624] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:27.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:82:0) after 1m0s
[05:10:27.643] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:82:0)
[05:10:27.643] Timer tick, asking Buildbucket for the build status
[05:10:27.694] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:27.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:83:0) after 1m0s
[05:11:27.712] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:83:0)
[05:11:27.712] Timer tick, asking Buildbucket for the build status
[05:11:27.854] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:27.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:84:0) after 1m0s
[05:12:27.872] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:84:0)
[05:12:27.872] Timer tick, asking Buildbucket for the build status
[05:12:27.941] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:27.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:85:0) after 1m0s
[05:13:27.962] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:85:0)
[05:13:27.962] Timer tick, asking Buildbucket for the build status
[05:13:28.051] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:28.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:86:0) after 1m0s
[05:14:28.091] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:86:0)
[05:14:28.091] Timer tick, asking Buildbucket for the build status
[05:14:28.716] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:28.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:87:0) after 1m0s
[05:15:28.733] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:87:0)
[05:15:28.733] Timer tick, asking Buildbucket for the build status
[05:15:29.133] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:29.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:88:0) after 1m0s
[05:16:29.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:88:0)
[05:16:29.153] Timer tick, asking Buildbucket for the build status
[05:16:29.210] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:29.210] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:89:0) after 1m0s
[05:17:29.229] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:89:0)
[05:17:29.229] Timer tick, asking Buildbucket for the build status
[05:17:29.299] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:29.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:90:0) after 1m0s
[05:18:29.320] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:90:0)
[05:18:29.320] Timer tick, asking Buildbucket for the build status
[05:18:29.421] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:29.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:91:0) after 1m0s
[05:19:29.441] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:91:0)
[05:19:29.441] Timer tick, asking Buildbucket for the build status
[05:19:29.716] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:29.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:92:0) after 1m0s
[05:20:29.836] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:92:0)
[05:20:29.836] Timer tick, asking Buildbucket for the build status
[05:20:29.960] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:29.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:93:0) after 1m0s
[05:21:30.001] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:93:0)
[05:21:30.001] Timer tick, asking Buildbucket for the build status
[05:21:30.077] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:30.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:94:0) after 1m0s
[05:22:30.100] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:94:0)
[05:22:30.100] Timer tick, asking Buildbucket for the build status
[05:22:30.370] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:30.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:95:0) after 1m0s
[05:23:30.389] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:95:0)
[05:23:30.389] Timer tick, asking Buildbucket for the build status
[05:23:30.486] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:30.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:96:0) after 1m0s
[05:24:30.504] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:96:0)
[05:24:30.504] Timer tick, asking Buildbucket for the build status
[05:24:30.768] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:30.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:97:0) after 1m0s
[05:25:30.789] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:97:0)
[05:25:30.789] Timer tick, asking Buildbucket for the build status
[05:25:31.026] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:31.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:98:0) after 1m0s
[05:26:31.047] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:98:0)
[05:26:31.048] Timer tick, asking Buildbucket for the build status
[05:26:31.113] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:31.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:99:0) after 1m0s
[05:27:31.135] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:99:0)
[05:27:31.135] Timer tick, asking Buildbucket for the build status
[05:27:31.186] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:31.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:100:0) after 1m0s
[05:28:31.211] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:100:0)
[05:28:31.211] Timer tick, asking Buildbucket for the build status
[05:28:31.279] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:31.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:101:0) after 1m0s
[05:29:31.300] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:101:0)
[05:29:31.300] Timer tick, asking Buildbucket for the build status
[05:29:31.349] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:31.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:102:0) after 1m0s
[05:30:31.376] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:102:0)
[05:30:31.376] Timer tick, asking Buildbucket for the build status
[05:30:31.445] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:31.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:103:0) after 1m0s
[05:31:31.465] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:103:0)
[05:31:31.466] Timer tick, asking Buildbucket for the build status
[05:31:31.534] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:31.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:104:0) after 1m0s
[05:32:31.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:104:0)
[05:32:31.567] Timer tick, asking Buildbucket for the build status
[05:32:31.698] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:31.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:105:0) after 1m0s
[05:33:31.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:105:0)
[05:33:31.722] Timer tick, asking Buildbucket for the build status
[05:33:31.843] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:31.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:106:0) after 1m0s
[05:34:31.866] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:106:0)
[05:34:31.866] Timer tick, asking Buildbucket for the build status
[05:34:31.930] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:31.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:107:0) after 1m0s
[05:35:31.966] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:107:0)
[05:35:31.966] Timer tick, asking Buildbucket for the build status
[05:35:32.037] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:32.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:108:0) after 1m0s
[05:36:32.062] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:108:0)
[05:36:32.062] Timer tick, asking Buildbucket for the build status
[05:36:32.156] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:32.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:109:0) after 1m0s
[05:37:32.180] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:109:0)
[05:37:32.180] Timer tick, asking Buildbucket for the build status
[05:37:32.247] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:32.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:110:0) after 1m0s
[05:38:32.277] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:110:0)
[05:38:32.277] Timer tick, asking Buildbucket for the build status
[05:38:32.367] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:32.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:111:0) after 1m0s
[05:39:32.451] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:111:0)
[05:39:32.451] Timer tick, asking Buildbucket for the build status
[05:39:32.850] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:32.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:112:0) after 1m0s
[05:40:32.916] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:112:0)
[05:40:32.916] Timer tick, asking Buildbucket for the build status
[05:40:33.151] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:33.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:113:0) after 1m0s
[05:41:33.176] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:113:0)
[05:41:33.176] Timer tick, asking Buildbucket for the build status
[05:41:33.333] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:33.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:114:0) after 1m0s
[05:42:33.388] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:114:0)
[05:42:33.388] Timer tick, asking Buildbucket for the build status
[05:42:33.451] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:33.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:115:0) after 1m0s
[05:43:33.477] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:115:0)
[05:43:33.477] Timer tick, asking Buildbucket for the build status
[05:43:33.617] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:33.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:116:0) after 1m0s
[05:44:33.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:116:0)
[05:44:33.640] Timer tick, asking Buildbucket for the build status
[05:44:33.695] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:33.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:117:0) after 1m0s
[05:45:33.716] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:117:0)
[05:45:33.716] Timer tick, asking Buildbucket for the build status
[05:45:33.786] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:33.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:118:0) after 1m0s
[05:46:33.813] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:118:0)
[05:46:33.813] Timer tick, asking Buildbucket for the build status
[05:46:33.874] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:33.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:119:0) after 1m0s
[05:47:33.897] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:119:0)
[05:47:33.897] Timer tick, asking Buildbucket for the build status
[05:47:33.988] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:33.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:120:0) after 1m0s
[05:48:34.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:120:0)
[05:48:34.029] Timer tick, asking Buildbucket for the build status
[05:48:34.078] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:34.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:121:0) after 1m0s
[05:49:34.122] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:121:0)
[05:49:34.122] Timer tick, asking Buildbucket for the build status
[05:49:34.305] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:34.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:122:0) after 1m0s
[05:50:34.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:122:0)
[05:50:34.325] Timer tick, asking Buildbucket for the build status
[05:50:34.407] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:34.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:123:0) after 1m0s
[05:51:34.438] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:123:0)
[05:51:34.438] Timer tick, asking Buildbucket for the build status
[05:51:34.497] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:34.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:124:0) after 1m0s
[05:52:34.517] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:124:0)
[05:52:34.517] Timer tick, asking Buildbucket for the build status
[05:52:34.588] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:34.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:125:0) after 1m0s
[05:53:34.608] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:125:0)
[05:53:34.608] Timer tick, asking Buildbucket for the build status
[05:53:34.684] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:34.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:126:0) after 1m0s
[05:54:34.706] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:126:0)
[05:54:34.706] Timer tick, asking Buildbucket for the build status
[05:54:34.774] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:34.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:127:0) after 1m0s
[05:55:34.797] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:127:0)
[05:55:34.797] Timer tick, asking Buildbucket for the build status
[05:55:34.858] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:34.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:128:0) after 1m0s
[05:56:34.880] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:128:0)
[05:56:34.880] Timer tick, asking Buildbucket for the build status
[05:56:34.934] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:34.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:129:0) after 1m0s
[05:57:34.958] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:129:0)
[05:57:34.958] Timer tick, asking Buildbucket for the build status
[05:57:35.039] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:35.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:130:0) after 1m0s
[05:58:35.076] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:130:0)
[05:58:35.076] Timer tick, asking Buildbucket for the build status
[05:58:35.143] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:35.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:131:0) after 1m0s
[05:59:35.166] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:131:0)
[05:59:35.166] Timer tick, asking Buildbucket for the build status
[05:59:35.232] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:35.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:132:0) after 1m0s
[06:00:35.305] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:132:0)
[06:00:35.305] Timer tick, asking Buildbucket for the build status
[06:00:35.371] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:35.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:133:0) after 1m0s
[06:01:35.391] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:133:0)
[06:01:35.391] Timer tick, asking Buildbucket for the build status
[06:01:35.548] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:35.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:134:0) after 1m0s
[06:02:35.675] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:134:0)
[06:02:35.675] Timer tick, asking Buildbucket for the build status
[06:02:35.774] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:35.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:135:0) after 1m0s
[06:03:35.878] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:135:0)
[06:03:35.878] Timer tick, asking Buildbucket for the build status
[06:03:35.960] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:35.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:136:0) after 1m0s
[06:04:35.984] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:136:0)
[06:04:35.984] Timer tick, asking Buildbucket for the build status
[06:04:36.061] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:36.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:137:0) after 1m0s
[06:05:36.087] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:137:0)
[06:05:36.087] Timer tick, asking Buildbucket for the build status
[06:05:36.172] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:36.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:138:0) after 1m0s
[06:06:36.208] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:138:0)
[06:06:36.208] Timer tick, asking Buildbucket for the build status
[06:06:36.252] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:36.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:139:0) after 1m0s
[06:07:36.343] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:139:0)
[06:07:36.343] Timer tick, asking Buildbucket for the build status
[06:07:36.456] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:36.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:140:0) after 1m0s
[06:08:36.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:140:0)
[06:08:36.493] Timer tick, asking Buildbucket for the build status
[06:08:36.683] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:36.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:141:0) after 1m0s
[06:09:36.709] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:141:0)
[06:09:36.709] Timer tick, asking Buildbucket for the build status
[06:09:36.769] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:36.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:142:0) after 1m0s
[06:10:36.790] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:142:0)
[06:10:36.790] Timer tick, asking Buildbucket for the build status
[06:10:36.861] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:36.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:143:0) after 1m0s
[06:11:36.881] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:143:0)
[06:11:36.881] Timer tick, asking Buildbucket for the build status
[06:11:37.024] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:37.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:144:0) after 1m0s
[06:12:37.131] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:144:0)
[06:12:37.131] Timer tick, asking Buildbucket for the build status
[06:12:37.205] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:37.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:145:0) after 1m0s
[06:13:37.226] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:145:0)
[06:13:37.226] Timer tick, asking Buildbucket for the build status
[06:13:37.332] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:37.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:146:0) after 1m0s
[06:14:37.397] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:146:0)
[06:14:37.397] Timer tick, asking Buildbucket for the build status
[06:14:37.520] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:37.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:147:0) after 1m0s
[06:15:37.583] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:147:0)
[06:15:37.583] Timer tick, asking Buildbucket for the build status
[06:15:37.634] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:37.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:148:0) after 1m0s
[06:16:37.654] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:148:0)
[06:16:37.654] Timer tick, asking Buildbucket for the build status
[06:16:37.727] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:37.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:149:0) after 1m0s
[06:17:37.757] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:149:0)
[06:17:37.757] Timer tick, asking Buildbucket for the build status
[06:17:37.826] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:37.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:150:0) after 1m0s
[06:18:37.849] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:150:0)
[06:18:37.849] Timer tick, asking Buildbucket for the build status
[06:18:38.010] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:38.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:151:0) after 1m0s
[06:19:38.032] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:151:0)
[06:19:38.032] Timer tick, asking Buildbucket for the build status
[06:19:38.103] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:38.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:152:0) after 1m0s
[06:20:38.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:152:0)
[06:20:38.123] Timer tick, asking Buildbucket for the build status
[06:20:38.231] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:38.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:153:0) after 1m0s
[06:21:38.258] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:153:0)
[06:21:38.258] Timer tick, asking Buildbucket for the build status
[06:21:38.329] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:38.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:154:0) after 1m0s
[06:22:38.353] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:154:0)
[06:22:38.353] Timer tick, asking Buildbucket for the build status
[06:22:38.440] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:38.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:155:0) after 1m0s
[06:23:38.555] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:155:0)
[06:23:38.555] Timer tick, asking Buildbucket for the build status
[06:23:38.730] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:38.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:156:0) after 1m0s
[06:24:38.761] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:156:0)
[06:24:38.761] Timer tick, asking Buildbucket for the build status
[06:24:38.828] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:38.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:157:0) after 1m0s
[06:25:38.856] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:157:0)
[06:25:38.856] Timer tick, asking Buildbucket for the build status
[06:25:39.022] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:39.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:158:0) after 1m0s
[06:26:39.042] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:158:0)
[06:26:39.042] Timer tick, asking Buildbucket for the build status
[06:26:39.083] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:39.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:159:0) after 1m0s
[06:27:39.121] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:159:0)
[06:27:39.121] Timer tick, asking Buildbucket for the build status
[06:27:39.275] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:39.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:160:0) after 1m0s
[06:28:39.296] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:160:0)
[06:28:39.296] Timer tick, asking Buildbucket for the build status
[06:28:39.529] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:39.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:161:0) after 1m0s
[06:29:39.563] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:161:0)
[06:29:39.563] Timer tick, asking Buildbucket for the build status
[06:29:39.652] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:39.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:162:0) after 1m0s
[06:30:39.691] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:162:0)
[06:30:39.691] Timer tick, asking Buildbucket for the build status
[06:30:39.890] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:39.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:163:0) after 1m0s
[06:31:39.912] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:163:0)
[06:31:39.912] Timer tick, asking Buildbucket for the build status
[06:31:40.018] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:40.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:164:0) after 1m0s
[06:32:40.041] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:164:0)
[06:32:40.041] Timer tick, asking Buildbucket for the build status
[06:32:40.186] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:40.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:165:0) after 1m0s
[06:33:40.206] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:165:0)
[06:33:40.206] Timer tick, asking Buildbucket for the build status
[06:33:40.327] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:40.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:166:0) after 1m0s
[06:34:40.359] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:166:0)
[06:34:40.359] Timer tick, asking Buildbucket for the build status
[06:34:40.584] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:40.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:167:0) after 1m0s
[06:35:40.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:167:0)
[06:35:40.606] Timer tick, asking Buildbucket for the build status
[06:35:40.659] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:168:0) after 1m0s
[06:36:40.673] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:168:0)
[06:36:40.673] Timer tick, asking Buildbucket for the build status
[06:36:40.766] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:169:0) after 1m0s
[06:37:40.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:169:0)
[06:37:40.786] Timer tick, asking Buildbucket for the build status
[06:37:41.312] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:41.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:170:0) after 1m0s
[06:38:41.346] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:170:0)
[06:38:41.346] Timer tick, asking Buildbucket for the build status
[06:38:41.477] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:41.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:171:0) after 1m0s
[06:39:41.531] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:171:0)
[06:39:41.531] Timer tick, asking Buildbucket for the build status
[06:39:41.767] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:41.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:172:0) after 1m0s
[06:40:41.834] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:172:0)
[06:40:41.834] Timer tick, asking Buildbucket for the build status
[06:40:41.928] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:41.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:173:0) after 1m0s
[06:41:41.951] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:173:0)
[06:41:41.951] Timer tick, asking Buildbucket for the build status
[06:41:42.027] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:42.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:174:0) after 1m0s
[06:42:42.084] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:174:0)
[06:42:42.084] Timer tick, asking Buildbucket for the build status
[06:42:42.245] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:42.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:175:0) after 1m0s
[06:43:42.278] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:175:0)
[06:43:42.278] Timer tick, asking Buildbucket for the build status
[06:43:42.344] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:42.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:176:0) after 1m0s
[06:44:42.389] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:176:0)
[06:44:42.389] Timer tick, asking Buildbucket for the build status
[06:44:42.866] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:42.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:177:0) after 1m0s
[06:45:42.899] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:177:0)
[06:45:42.899] Timer tick, asking Buildbucket for the build status
[06:45:43.050] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:43.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:178:0) after 1m0s
[06:46:43.082] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:178:0)
[06:46:43.082] Timer tick, asking Buildbucket for the build status
[06:46:43.132] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:43.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:179:0) after 1m0s
[06:47:43.158] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:179:0)
[06:47:43.158] Timer tick, asking Buildbucket for the build status
[06:47:43.222] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:43.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:180:0) after 1m0s
[06:48:43.243] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:180:0)
[06:48:43.243] Timer tick, asking Buildbucket for the build status
[06:48:43.421] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:43.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:181:0) after 1m0s
[06:49:43.444] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:181:0)
[06:49:43.444] Timer tick, asking Buildbucket for the build status
[06:49:43.531] Build 8927739650237816880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:43.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9093169712454127072:182:0) after 1m0s
[06:50:27.072] Received PubSub notification, asking Buildbucket for the build status
[06:50:27.145] Build 8927739650237816880: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[06:50:27.145] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary": true,
    "canary_preference": "AUTO",
    "completed_ts": "1544251826361723",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1544241014214048",
    "failure_reason": "INFRA_FAILURE",
    "id": "8927739650237816880",
    "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\": \"14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"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\": [\"3921-6311e5b\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-r5wk\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"]}}, \"properties\": {}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1544241017085804",
    "status": "COMPLETED",
    "status_changed_ts": "1544251826774724",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/82",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9093169712454127072",
      "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/82",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8927739650237816880",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:1",
      "swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/14b228f23bb875bd4a25d3e88bd5f15d1bc8c87a",
      "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/8927739650237816880/+/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:9093169712454127072",
      "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:eb0b43081c973a89e3df43ecc3d19a08676f42b3",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:41a4c16ff1cd8710",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1544251826775741",
    "url": "https://ci.chromium.org/b/8927739650237816880",
    "utcnow_ts": "1544251827129901"
  }
}
[06:50:27.145] Invocation finished in 3h0m14.427538933s with status FAILED