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

• 321c47ddeedcf7e87ffd2c127500e4ef9888fb69 (6 years ago)

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

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

[16:11:19.192] New invocation is queued and will start shortly
[16:11:20.727] Starting the invocation (attempt 1)
[16:11:20.766] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:11:20.769] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:11:20.769] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9089046222503533136",
  "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\":\"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
  "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:9089046222503533136",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
    "gitiles_ref:refs/heads/master"
  ]
}
[16:11:23.399] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548173480885561",
    "id": "8923616160065284560",
    "lease_expiration_ts": "1548324682962546",
    "lease_key": "646549970",
    "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\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1548173483200513",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/97",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089046222503533136",
      "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/97",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8923616160065284560",
      "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/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "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/8923616160065284560/+/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:9089046222503533136",
      "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:4122e711faf74a549260c6de6a369a8fe79a9d3e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:428f2620dd363510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548173483200591",
    "url": "https://ci.chromium.org/b/8923616160065284560",
    "utcnow_ts": "1548173483353685"
  }
}
[16:11:23.399] Task URL: https://ci.chromium.org/b/8923616160065284560
[16:11:23.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:2:0) after 1m0s
[16:12:23.415] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:2:0)
[16:12:23.415] Timer tick, asking Buildbucket for the build status
[16:12:23.477] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:23.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:3:0) after 1m0s
[16:13:23.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:3:0)
[16:13:23.493] Timer tick, asking Buildbucket for the build status
[16:13:23.646] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:23.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:4:0) after 1m0s
[16:14:23.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:4:0)
[16:14:23.640] Timer tick, asking Buildbucket for the build status
[16:14:23.803] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:23.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:5:0) after 1m0s
[16:15:23.818] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:5:0)
[16:15:23.818] Timer tick, asking Buildbucket for the build status
[16:15:23.879] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:23.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:6:0) after 1m0s
[16:16:23.899] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:6:0)
[16:16:23.899] Timer tick, asking Buildbucket for the build status
[16:16:23.951] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:23.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:7:0) after 1m0s
[16:17:23.968] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:7:0)
[16:17:23.968] Timer tick, asking Buildbucket for the build status
[16:17:24.071] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:24.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:8:0) after 1m0s
[16:18:24.098] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:8:0)
[16:18:24.098] Timer tick, asking Buildbucket for the build status
[16:18:24.133] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:24.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:9:0) after 1m0s
[16:19:24.147] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:9:0)
[16:19:24.147] Timer tick, asking Buildbucket for the build status
[16:19:24.208] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:24.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:10:0) after 1m0s
[16:20:24.225] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:10:0)
[16:20:24.225] Timer tick, asking Buildbucket for the build status
[16:20:24.433] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:24.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:11:0) after 1m0s
[16:21:24.539] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:11:0)
[16:21:24.539] Timer tick, asking Buildbucket for the build status
[16:21:24.628] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:24.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:12:0) after 1m0s
[16:22:24.642] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:12:0)
[16:22:24.642] Timer tick, asking Buildbucket for the build status
[16:22:24.694] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:24.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:13:0) after 1m0s
[16:23:24.708] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:13:0)
[16:23:24.708] Timer tick, asking Buildbucket for the build status
[16:23:24.766] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:24.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:14:0) after 1m0s
[16:24:24.781] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:14:0)
[16:24:24.781] Timer tick, asking Buildbucket for the build status
[16:24:24.811] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:24.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:15:0) after 1m0s
[16:25:24.832] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:15:0)
[16:25:24.832] Timer tick, asking Buildbucket for the build status
[16:25:25.333] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:25.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:16:0) after 1m0s
[16:26:25.346] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:16:0)
[16:26:25.346] Timer tick, asking Buildbucket for the build status
[16:26:25.466] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:25.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:17:0) after 1m0s
[16:27:25.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:17:0)
[16:27:25.493] Timer tick, asking Buildbucket for the build status
[16:27:25.609] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:25.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:18:0) after 1m0s
[16:28:25.632] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:18:0)
[16:28:25.632] Timer tick, asking Buildbucket for the build status
[16:28:25.686] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:25.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:19:0) after 1m0s
[16:29:25.704] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:19:0)
[16:29:25.704] Timer tick, asking Buildbucket for the build status
[16:29:25.747] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:25.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:20:0) after 1m0s
[16:30:25.765] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:20:0)
[16:30:25.765] Timer tick, asking Buildbucket for the build status
[16:30:25.864] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:25.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:21:0) after 1m0s
[16:31:25.883] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:21:0)
[16:31:25.883] Timer tick, asking Buildbucket for the build status
[16:31:25.931] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:25.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:22:0) after 1m0s
[16:32:25.946] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:22:0)
[16:32:25.946] Timer tick, asking Buildbucket for the build status
[16:32:25.997] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:25.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:23:0) after 1m0s
[16:33:26.032] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:23:0)
[16:33:26.032] Timer tick, asking Buildbucket for the build status
[16:33:26.099] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:26.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:24:0) after 1m0s
[16:34:26.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:24:0)
[16:34:26.157] Timer tick, asking Buildbucket for the build status
[16:34:26.215] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:26.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:25:0) after 1m0s
[16:35:26.251] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:25:0)
[16:35:26.251] Timer tick, asking Buildbucket for the build status
[16:35:26.352] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:26.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:26:0) after 1m0s
[16:36:26.381] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:26:0)
[16:36:26.381] Timer tick, asking Buildbucket for the build status
[16:36:26.514] Build 8923616160065284560: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:26.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:27:0) after 1m0s
[16:37:03.656] Received PubSub notification, asking Buildbucket for the build status
[16:37:03.693] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:26.507] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:27:0)
[16:37:26.507] Timer tick, asking Buildbucket for the build status
[16:37:26.621] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:26.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:29:0) after 1m0s
[16:38:26.638] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:29:0)
[16:38:26.638] Timer tick, asking Buildbucket for the build status
[16:38:26.759] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:26.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:30:0) after 1m0s
[16:39:26.825] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:30:0)
[16:39:26.825] Timer tick, asking Buildbucket for the build status
[16:39:26.895] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:26.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:31:0) after 1m0s
[16:40:26.913] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:31:0)
[16:40:26.913] Timer tick, asking Buildbucket for the build status
[16:40:27.033] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:27.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:32:0) after 1m0s
[16:41:27.055] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:32:0)
[16:41:27.055] Timer tick, asking Buildbucket for the build status
[16:41:27.892] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:27.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:33:0) after 1m0s
[16:42:27.909] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:33:0)
[16:42:27.909] Timer tick, asking Buildbucket for the build status
[16:42:27.988] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:27.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:34:0) after 1m0s
[16:43:28.005] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:34:0)
[16:43:28.005] Timer tick, asking Buildbucket for the build status
[16:43:28.079] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:28.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:35:0) after 1m0s
[16:44:28.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:35:0)
[16:44:28.113] Timer tick, asking Buildbucket for the build status
[16:44:28.178] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:28.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:36:0) after 1m0s
[16:45:28.197] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:36:0)
[16:45:28.197] Timer tick, asking Buildbucket for the build status
[16:45:28.233] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:28.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:37:0) after 1m0s
[16:46:28.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:37:0)
[16:46:28.395] Timer tick, asking Buildbucket for the build status
[16:46:28.449] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:28.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:38:0) after 1m0s
[16:47:28.465] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:38:0)
[16:47:28.465] Timer tick, asking Buildbucket for the build status
[16:47:28.524] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:28.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:39:0) after 1m0s
[16:48:28.539] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:39:0)
[16:48:28.539] Timer tick, asking Buildbucket for the build status
[16:48:28.582] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:28.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:40:0) after 1m0s
[16:49:28.600] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:40:0)
[16:49:28.600] Timer tick, asking Buildbucket for the build status
[16:49:28.692] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:28.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:41:0) after 1m0s
[16:50:28.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:41:0)
[16:50:28.723] Timer tick, asking Buildbucket for the build status
[16:50:28.763] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:28.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:42:0) after 1m0s
[16:51:28.781] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:42:0)
[16:51:28.781] Timer tick, asking Buildbucket for the build status
[16:51:28.818] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:28.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:43:0) after 1m0s
[16:52:28.826] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:43:0)
[16:52:28.826] Timer tick, asking Buildbucket for the build status
[16:52:28.883] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:28.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:44:0) after 1m0s
[16:53:28.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:44:0)
[16:53:28.898] Timer tick, asking Buildbucket for the build status
[16:53:28.940] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:28.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:45:0) after 1m0s
[16:54:28.955] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:45:0)
[16:54:28.955] Timer tick, asking Buildbucket for the build status
[16:54:29.017] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:29.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:46:0) after 1m0s
[16:55:29.051] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:46:0)
[16:55:29.051] Timer tick, asking Buildbucket for the build status
[16:55:29.087] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:29.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:47:0) after 1m0s
[16:56:29.105] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:47:0)
[16:56:29.105] Timer tick, asking Buildbucket for the build status
[16:56:29.188] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:29.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:48:0) after 1m0s
[16:57:29.209] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:48:0)
[16:57:29.209] Timer tick, asking Buildbucket for the build status
[16:57:29.249] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:29.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:49:0) after 1m0s
[16:58:29.270] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:49:0)
[16:58:29.270] Timer tick, asking Buildbucket for the build status
[16:58:29.336] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:29.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:50:0) after 1m0s
[16:59:29.354] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:50:0)
[16:59:29.354] Timer tick, asking Buildbucket for the build status
[16:59:29.393] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:29.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:51:0) after 1m0s
[17:00:29.420] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:51:0)
[17:00:29.420] Timer tick, asking Buildbucket for the build status
[17:00:29.506] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:29.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:52:0) after 1m0s
[17:01:29.524] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:52:0)
[17:01:29.524] Timer tick, asking Buildbucket for the build status
[17:01:29.568] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:29.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:53:0) after 1m0s
[17:02:29.584] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:53:0)
[17:02:29.584] Timer tick, asking Buildbucket for the build status
[17:02:29.682] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:29.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:54:0) after 1m0s
[17:03:29.700] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:54:0)
[17:03:29.700] Timer tick, asking Buildbucket for the build status
[17:03:29.747] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:29.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:55:0) after 1m0s
[17:04:29.764] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:55:0)
[17:04:29.764] Timer tick, asking Buildbucket for the build status
[17:04:29.827] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:29.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:56:0) after 1m0s
[17:05:29.844] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:56:0)
[17:05:29.844] Timer tick, asking Buildbucket for the build status
[17:05:29.905] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:29.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:57:0) after 1m0s
[17:06:29.921] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:57:0)
[17:06:29.921] Timer tick, asking Buildbucket for the build status
[17:06:29.962] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:29.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:58:0) after 1m0s
[17:07:29.982] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:58:0)
[17:07:29.982] Timer tick, asking Buildbucket for the build status
[17:07:30.054] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:30.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:59:0) after 1m0s
[17:08:30.089] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:59:0)
[17:08:30.089] Timer tick, asking Buildbucket for the build status
[17:08:30.154] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:30.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:60:0) after 1m0s
[17:09:30.169] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:60:0)
[17:09:30.169] Timer tick, asking Buildbucket for the build status
[17:09:30.208] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:30.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:61:0) after 1m0s
[17:10:30.231] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:61:0)
[17:10:30.231] Timer tick, asking Buildbucket for the build status
[17:10:30.279] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:30.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:62:0) after 1m0s
[17:11:30.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:62:0)
[17:11:30.298] Timer tick, asking Buildbucket for the build status
[17:11:30.342] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:30.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:63:0) after 1m0s
[17:12:30.372] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:63:0)
[17:12:30.372] Timer tick, asking Buildbucket for the build status
[17:12:30.432] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:30.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:64:0) after 1m0s
[17:13:30.450] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:64:0)
[17:13:30.450] Timer tick, asking Buildbucket for the build status
[17:13:30.505] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:30.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:65:0) after 1m0s
[17:14:30.523] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:65:0)
[17:14:30.523] Timer tick, asking Buildbucket for the build status
[17:14:30.567] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:30.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:66:0) after 1m0s
[17:15:30.593] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:66:0)
[17:15:30.593] Timer tick, asking Buildbucket for the build status
[17:15:30.638] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:30.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:67:0) after 1m0s
[17:16:30.653] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:67:0)
[17:16:30.653] Timer tick, asking Buildbucket for the build status
[17:16:30.686] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:30.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:68:0) after 1m0s
[17:17:30.703] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:68:0)
[17:17:30.703] Timer tick, asking Buildbucket for the build status
[17:17:30.739] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:30.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:69:0) after 1m0s
[17:18:30.756] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:69:0)
[17:18:30.756] Timer tick, asking Buildbucket for the build status
[17:18:30.928] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:30.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:70:0) after 1m0s
[17:19:30.948] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:70:0)
[17:19:30.948] Timer tick, asking Buildbucket for the build status
[17:19:31.001] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:31.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:71:0) after 1m0s
[17:20:31.027] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:71:0)
[17:20:31.027] Timer tick, asking Buildbucket for the build status
[17:20:31.112] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:31.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:72:0) after 1m0s
[17:21:31.137] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:72:0)
[17:21:31.137] Timer tick, asking Buildbucket for the build status
[17:21:31.324] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:31.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:73:0) after 1m0s
[17:22:31.435] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:73:0)
[17:22:31.435] Timer tick, asking Buildbucket for the build status
[17:22:31.483] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:31.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:74:0) after 1m0s
[17:23:31.503] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:74:0)
[17:23:31.503] Timer tick, asking Buildbucket for the build status
[17:23:31.589] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:31.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:75:0) after 1m0s
[17:24:31.605] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:75:0)
[17:24:31.605] Timer tick, asking Buildbucket for the build status
[17:24:31.656] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:31.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:76:0) after 1m0s
[17:25:31.684] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:76:0)
[17:25:31.684] Timer tick, asking Buildbucket for the build status
[17:25:31.869] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:31.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:77:0) after 1m0s
[17:26:31.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:77:0)
[17:26:31.898] Timer tick, asking Buildbucket for the build status
[17:26:31.930] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:31.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:78:0) after 1m0s
[17:27:31.947] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:78:0)
[17:27:31.947] Timer tick, asking Buildbucket for the build status
[17:27:32.021] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:32.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:79:0) after 1m0s
[17:28:32.046] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:79:0)
[17:28:32.046] Timer tick, asking Buildbucket for the build status
[17:28:32.127] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:32.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:80:0) after 1m0s
[17:29:32.144] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:80:0)
[17:29:32.144] Timer tick, asking Buildbucket for the build status
[17:29:32.334] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:32.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:81:0) after 1m0s
[17:30:32.356] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:81:0)
[17:30:32.356] Timer tick, asking Buildbucket for the build status
[17:30:32.422] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:32.422] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:82:0) after 1m0s
[17:31:32.443] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:82:0)
[17:31:32.443] Timer tick, asking Buildbucket for the build status
[17:31:32.552] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:32.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:83:0) after 1m0s
[17:32:32.602] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:83:0)
[17:32:32.602] Timer tick, asking Buildbucket for the build status
[17:32:32.688] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:32.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:84:0) after 1m0s
[17:33:32.704] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:84:0)
[17:33:32.704] Timer tick, asking Buildbucket for the build status
[17:33:32.849] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:32.849] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:85:0) after 1m0s
[17:34:32.881] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:85:0)
[17:34:32.881] Timer tick, asking Buildbucket for the build status
[17:34:33.009] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:33.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:86:0) after 1m0s
[17:35:33.039] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:86:0)
[17:35:33.039] Timer tick, asking Buildbucket for the build status
[17:35:33.127] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:33.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:87:0) after 1m0s
[17:36:33.159] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:87:0)
[17:36:33.159] Timer tick, asking Buildbucket for the build status
[17:36:33.252] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:33.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:88:0) after 1m0s
[17:37:33.274] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:88:0)
[17:37:33.274] Timer tick, asking Buildbucket for the build status
[17:37:33.391] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:33.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:89:0) after 1m0s
[17:38:33.413] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:89:0)
[17:38:33.413] Timer tick, asking Buildbucket for the build status
[17:38:33.484] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:33.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:90:0) after 1m0s
[17:39:33.509] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:90:0)
[17:39:33.509] Timer tick, asking Buildbucket for the build status
[17:39:33.665] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:33.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:91:0) after 1m0s
[17:40:33.686] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:91:0)
[17:40:33.686] Timer tick, asking Buildbucket for the build status
[17:40:33.737] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:33.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:92:0) after 1m0s
[17:41:33.756] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:92:0)
[17:41:33.756] Timer tick, asking Buildbucket for the build status
[17:41:33.835] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:33.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:93:0) after 1m0s
[17:42:33.929] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:93:0)
[17:42:33.929] Timer tick, asking Buildbucket for the build status
[17:42:34.227] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:34.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:94:0) after 1m0s
[17:43:34.245] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:94:0)
[17:43:34.245] Timer tick, asking Buildbucket for the build status
[17:43:34.302] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:34.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:95:0) after 1m0s
[17:44:34.326] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:95:0)
[17:44:34.326] Timer tick, asking Buildbucket for the build status
[17:44:34.374] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:34.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:96:0) after 1m0s
[17:45:34.393] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:96:0)
[17:45:34.393] Timer tick, asking Buildbucket for the build status
[17:45:34.469] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:34.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:97:0) after 1m0s
[17:46:34.487] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:97:0)
[17:46:34.487] Timer tick, asking Buildbucket for the build status
[17:46:34.543] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:34.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:98:0) after 1m0s
[17:47:34.560] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:98:0)
[17:47:34.560] Timer tick, asking Buildbucket for the build status
[17:47:34.635] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:34.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:99:0) after 1m0s
[17:48:34.652] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:99:0)
[17:48:34.652] Timer tick, asking Buildbucket for the build status
[17:48:34.747] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:34.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:100:0) after 1m0s
[17:49:34.779] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:100:0)
[17:49:34.779] Timer tick, asking Buildbucket for the build status
[17:49:34.861] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:34.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:101:0) after 1m0s
[17:50:34.877] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:101:0)
[17:50:34.877] Timer tick, asking Buildbucket for the build status
[17:50:34.990] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:34.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:102:0) after 1m0s
[17:51:35.006] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:102:0)
[17:51:35.006] Timer tick, asking Buildbucket for the build status
[17:51:35.801] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:35.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:103:0) after 1m0s
[17:52:35.832] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:103:0)
[17:52:35.832] Timer tick, asking Buildbucket for the build status
[17:52:35.943] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:35.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:104:0) after 1m0s
[17:53:35.962] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:104:0)
[17:53:35.962] Timer tick, asking Buildbucket for the build status
[17:53:36.064] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:36.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:105:0) after 1m0s
[17:54:36.082] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:105:0)
[17:54:36.082] Timer tick, asking Buildbucket for the build status
[17:54:36.378] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:36.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:106:0) after 1m0s
[17:55:36.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:106:0)
[17:55:36.394] Timer tick, asking Buildbucket for the build status
[17:55:36.594] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:36.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:107:0) after 1m0s
[17:56:36.604] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:107:0)
[17:56:36.604] Timer tick, asking Buildbucket for the build status
[17:56:36.781] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:36.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:108:0) after 1m0s
[17:57:36.798] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:108:0)
[17:57:36.798] Timer tick, asking Buildbucket for the build status
[17:57:36.851] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:36.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:109:0) after 1m0s
[17:58:36.873] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:109:0)
[17:58:36.873] Timer tick, asking Buildbucket for the build status
[17:58:36.948] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:36.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:110:0) after 1m0s
[17:59:36.965] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:110:0)
[17:59:36.965] Timer tick, asking Buildbucket for the build status
[17:59:37.074] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:37.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:111:0) after 1m0s
[18:00:37.096] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:111:0)
[18:00:37.096] Timer tick, asking Buildbucket for the build status
[18:00:37.259] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:37.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:112:0) after 1m0s
[18:01:37.276] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:112:0)
[18:01:37.276] Timer tick, asking Buildbucket for the build status
[18:01:37.418] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:37.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:113:0) after 1m0s
[18:02:37.440] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:113:0)
[18:02:37.440] Timer tick, asking Buildbucket for the build status
[18:02:37.536] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:37.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:114:0) after 1m0s
[18:03:37.553] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:114:0)
[18:03:37.553] Timer tick, asking Buildbucket for the build status
[18:03:37.606] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:37.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:115:0) after 1m0s
[18:04:37.623] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:115:0)
[18:04:37.623] Timer tick, asking Buildbucket for the build status
[18:04:37.676] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:37.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:116:0) after 1m0s
[18:05:37.699] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:116:0)
[18:05:37.699] Timer tick, asking Buildbucket for the build status
[18:05:37.996] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:37.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:117:0) after 1m0s
[18:06:38.014] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:117:0)
[18:06:38.014] Timer tick, asking Buildbucket for the build status
[18:06:38.131] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:38.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:118:0) after 1m0s
[18:07:38.152] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:118:0)
[18:07:38.152] Timer tick, asking Buildbucket for the build status
[18:07:38.217] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:38.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:119:0) after 1m0s
[18:08:38.284] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:119:0)
[18:08:38.284] Timer tick, asking Buildbucket for the build status
[18:08:38.411] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:38.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:120:0) after 1m0s
[18:09:38.538] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:120:0)
[18:09:38.538] Timer tick, asking Buildbucket for the build status
[18:09:38.630] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:38.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:121:0) after 1m0s
[18:10:38.652] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:121:0)
[18:10:38.652] Timer tick, asking Buildbucket for the build status
[18:10:38.841] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:38.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:122:0) after 1m0s
[18:11:38.859] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:122:0)
[18:11:38.859] Timer tick, asking Buildbucket for the build status
[18:11:38.916] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:38.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:123:0) after 1m0s
[18:12:39.003] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:123:0)
[18:12:39.003] Timer tick, asking Buildbucket for the build status
[18:12:39.393] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:39.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:124:0) after 1m0s
[18:13:39.412] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:124:0)
[18:13:39.412] Timer tick, asking Buildbucket for the build status
[18:13:39.487] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:39.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:125:0) after 1m0s
[18:14:39.509] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:125:0)
[18:14:39.509] Timer tick, asking Buildbucket for the build status
[18:14:39.557] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:39.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:126:0) after 1m0s
[18:15:39.575] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:126:0)
[18:15:39.575] Timer tick, asking Buildbucket for the build status
[18:15:39.635] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:39.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:127:0) after 1m0s
[18:16:39.739] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:127:0)
[18:16:39.739] Timer tick, asking Buildbucket for the build status
[18:16:39.865] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:39.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:128:0) after 1m0s
[18:17:39.884] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:128:0)
[18:17:39.884] Timer tick, asking Buildbucket for the build status
[18:17:39.969] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:39.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:129:0) after 1m0s
[18:18:39.988] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:129:0)
[18:18:39.988] Timer tick, asking Buildbucket for the build status
[18:18:40.133] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:40.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:130:0) after 1m0s
[18:19:40.170] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:130:0)
[18:19:40.170] Timer tick, asking Buildbucket for the build status
[18:19:40.296] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:40.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:131:0) after 1m0s
[18:20:40.330] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:131:0)
[18:20:40.330] Timer tick, asking Buildbucket for the build status
[18:20:40.451] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:40.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:132:0) after 1m0s
[18:21:40.469] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:132:0)
[18:21:40.469] Timer tick, asking Buildbucket for the build status
[18:21:40.608] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:40.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:133:0) after 1m0s
[18:22:40.674] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:133:0)
[18:22:40.674] Timer tick, asking Buildbucket for the build status
[18:22:40.946] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:40.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:134:0) after 1m0s
[18:23:41.058] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:134:0)
[18:23:41.058] Timer tick, asking Buildbucket for the build status
[18:23:41.147] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:41.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:135:0) after 1m0s
[18:24:41.151] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:135:0)
[18:24:41.151] Timer tick, asking Buildbucket for the build status
[18:24:41.421] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:41.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:136:0) after 1m0s
[18:25:41.441] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:136:0)
[18:25:41.441] Timer tick, asking Buildbucket for the build status
[18:25:41.582] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:41.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:137:0) after 1m0s
[18:26:41.604] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:137:0)
[18:26:41.604] Timer tick, asking Buildbucket for the build status
[18:26:41.804] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:41.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:138:0) after 1m0s
[18:27:41.839] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:138:0)
[18:27:41.839] Timer tick, asking Buildbucket for the build status
[18:27:42.068] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:42.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:139:0) after 1m0s
[18:28:42.096] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:139:0)
[18:28:42.096] Timer tick, asking Buildbucket for the build status
[18:28:42.214] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:42.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:140:0) after 1m0s
[18:29:42.236] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:140:0)
[18:29:42.236] Timer tick, asking Buildbucket for the build status
[18:29:42.306] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:42.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:141:0) after 1m0s
[18:30:42.326] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:141:0)
[18:30:42.326] Timer tick, asking Buildbucket for the build status
[18:30:42.400] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:42.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:142:0) after 1m0s
[18:31:42.432] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:142:0)
[18:31:42.432] Timer tick, asking Buildbucket for the build status
[18:31:42.529] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:42.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:143:0) after 1m0s
[18:32:42.546] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:143:0)
[18:32:42.546] Timer tick, asking Buildbucket for the build status
[18:32:42.645] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:42.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:144:0) after 1m0s
[18:33:42.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:144:0)
[18:33:42.667] Timer tick, asking Buildbucket for the build status
[18:33:42.731] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:42.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:145:0) after 1m0s
[18:34:42.751] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:145:0)
[18:34:42.751] Timer tick, asking Buildbucket for the build status
[18:34:43.039] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:43.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:146:0) after 1m0s
[18:35:43.058] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:146:0)
[18:35:43.058] Timer tick, asking Buildbucket for the build status
[18:35:43.163] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:43.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:147:0) after 1m0s
[18:36:43.193] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:147:0)
[18:36:43.193] Timer tick, asking Buildbucket for the build status
[18:36:43.264] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:43.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:148:0) after 1m0s
[18:37:43.283] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:148:0)
[18:37:43.283] Timer tick, asking Buildbucket for the build status
[18:37:43.367] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:43.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:149:0) after 1m0s
[18:38:43.387] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:149:0)
[18:38:43.387] Timer tick, asking Buildbucket for the build status
[18:38:43.650] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:43.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:150:0) after 1m0s
[18:39:43.741] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:150:0)
[18:39:43.741] Timer tick, asking Buildbucket for the build status
[18:39:43.916] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:43.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:151:0) after 1m0s
[18:40:43.937] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:151:0)
[18:40:43.937] Timer tick, asking Buildbucket for the build status
[18:40:44.015] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:44.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:152:0) after 1m0s
[18:41:44.077] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:152:0)
[18:41:44.077] Timer tick, asking Buildbucket for the build status
[18:41:44.136] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:44.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:153:0) after 1m0s
[18:42:44.167] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:153:0)
[18:42:44.167] Timer tick, asking Buildbucket for the build status
[18:42:44.282] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:44.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:154:0) after 1m0s
[18:43:44.309] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:154:0)
[18:43:44.309] Timer tick, asking Buildbucket for the build status
[18:43:44.363] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:44.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:155:0) after 1m0s
[18:44:44.422] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:155:0)
[18:44:44.422] Timer tick, asking Buildbucket for the build status
[18:44:44.469] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:44.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:156:0) after 1m0s
[18:45:44.503] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:156:0)
[18:45:44.503] Timer tick, asking Buildbucket for the build status
[18:45:44.642] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:44.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:157:0) after 1m0s
[18:46:44.662] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:157:0)
[18:46:44.662] Timer tick, asking Buildbucket for the build status
[18:46:44.740] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:44.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:158:0) after 1m0s
[18:47:44.758] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:158:0)
[18:47:44.758] Timer tick, asking Buildbucket for the build status
[18:47:44.824] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:44.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:159:0) after 1m0s
[18:48:45.138] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:159:0)
[18:48:45.138] Timer tick, asking Buildbucket for the build status
[18:48:45.229] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:45.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:160:0) after 1m0s
[18:49:45.249] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:160:0)
[18:49:45.249] Timer tick, asking Buildbucket for the build status
[18:49:45.456] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:45.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:161:0) after 1m0s
[18:50:45.482] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:161:0)
[18:50:45.482] Timer tick, asking Buildbucket for the build status
[18:50:45.551] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:45.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:162:0) after 1m0s
[18:51:45.570] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:162:0)
[18:51:45.570] Timer tick, asking Buildbucket for the build status
[18:51:45.651] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:45.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:163:0) after 1m0s
[18:52:45.673] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:163:0)
[18:52:45.673] Timer tick, asking Buildbucket for the build status
[18:52:45.730] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:45.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:164:0) after 1m0s
[18:53:45.754] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:164:0)
[18:53:45.754] Timer tick, asking Buildbucket for the build status
[18:53:45.922] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:45.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:165:0) after 1m0s
[18:54:45.942] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:165:0)
[18:54:45.942] Timer tick, asking Buildbucket for the build status
[18:54:46.004] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:46.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:166:0) after 1m0s
[18:55:46.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:166:0)
[18:55:46.029] Timer tick, asking Buildbucket for the build status
[18:55:46.152] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:46.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:167:0) after 1m0s
[18:56:46.171] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:167:0)
[18:56:46.171] Timer tick, asking Buildbucket for the build status
[18:56:46.225] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:46.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:168:0) after 1m0s
[18:57:46.249] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:168:0)
[18:57:46.249] Timer tick, asking Buildbucket for the build status
[18:57:46.297] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:46.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:169:0) after 1m0s
[18:58:46.317] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:169:0)
[18:58:46.317] Timer tick, asking Buildbucket for the build status
[18:58:46.427] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:46.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:170:0) after 1m0s
[18:59:46.454] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:170:0)
[18:59:46.454] Timer tick, asking Buildbucket for the build status
[18:59:46.521] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:46.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:171:0) after 1m0s
[19:00:46.542] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:171:0)
[19:00:46.542] Timer tick, asking Buildbucket for the build status
[19:00:46.698] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:46.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:172:0) after 1m0s
[19:01:46.736] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:172:0)
[19:01:46.736] Timer tick, asking Buildbucket for the build status
[19:01:46.835] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:46.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:173:0) after 1m0s
[19:02:46.856] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:173:0)
[19:02:46.856] Timer tick, asking Buildbucket for the build status
[19:02:47.104] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:47.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:174:0) after 1m0s
[19:03:47.122] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:174:0)
[19:03:47.123] Timer tick, asking Buildbucket for the build status
[19:03:47.447] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:47.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:175:0) after 1m0s
[19:04:47.468] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:175:0)
[19:04:47.468] Timer tick, asking Buildbucket for the build status
[19:04:47.554] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:47.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:176:0) after 1m0s
[19:05:47.577] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:176:0)
[19:05:47.577] Timer tick, asking Buildbucket for the build status
[19:05:47.638] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:47.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:177:0) after 1m0s
[19:06:47.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:177:0)
[19:06:47.667] Timer tick, asking Buildbucket for the build status
[19:06:47.754] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:47.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:178:0) after 1m0s
[19:07:47.775] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:178:0)
[19:07:47.775] Timer tick, asking Buildbucket for the build status
[19:07:47.835] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:47.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:179:0) after 1m0s
[19:08:47.855] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:179:0)
[19:08:47.855] Timer tick, asking Buildbucket for the build status
[19:08:48.011] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:48.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:180:0) after 1m0s
[19:09:48.036] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:180:0)
[19:09:48.036] Timer tick, asking Buildbucket for the build status
[19:09:48.123] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:48.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:181:0) after 1m0s
[19:10:48.151] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:181:0)
[19:10:48.151] Timer tick, asking Buildbucket for the build status
[19:10:48.202] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:48.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:182:0) after 1m0s
[19:11:48.219] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:182:0)
[19:11:48.219] Timer tick, asking Buildbucket for the build status
[19:11:49.353] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:49.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:183:0) after 1m0s
[19:12:49.410] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:183:0)
[19:12:49.410] Timer tick, asking Buildbucket for the build status
[19:12:49.534] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:49.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:184:0) after 1m0s
[19:13:49.566] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:184:0)
[19:13:49.566] Timer tick, asking Buildbucket for the build status
[19:13:49.613] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:49.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:185:0) after 1m0s
[19:14:49.635] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:185:0)
[19:14:49.635] Timer tick, asking Buildbucket for the build status
[19:14:49.773] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:49.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:186:0) after 1m0s
[19:15:49.840] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:186:0)
[19:15:49.840] Timer tick, asking Buildbucket for the build status
[19:15:49.964] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:49.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:187:0) after 1m0s
[19:16:50.015] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:187:0)
[19:16:50.015] Timer tick, asking Buildbucket for the build status
[19:16:50.085] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:50.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:188:0) after 1m0s
[19:17:50.129] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:188:0)
[19:17:50.129] Timer tick, asking Buildbucket for the build status
[19:17:50.186] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:50.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:189:0) after 1m0s
[19:18:50.207] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:189:0)
[19:18:50.207] Timer tick, asking Buildbucket for the build status
[19:18:50.309] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:50.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:190:0) after 1m0s
[19:19:50.337] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:190:0)
[19:19:50.337] Timer tick, asking Buildbucket for the build status
[19:19:50.380] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:50.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:191:0) after 1m0s
[19:20:50.399] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:191:0)
[19:20:50.399] Timer tick, asking Buildbucket for the build status
[19:20:50.481] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:50.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:192:0) after 1m0s
[19:21:50.499] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:192:0)
[19:21:50.499] Timer tick, asking Buildbucket for the build status
[19:21:50.638] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:50.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:193:0) after 1m0s
[19:22:50.660] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:193:0)
[19:22:50.660] Timer tick, asking Buildbucket for the build status
[19:22:50.779] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:50.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:194:0) after 1m0s
[19:23:50.800] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:194:0)
[19:23:50.800] Timer tick, asking Buildbucket for the build status
[19:23:51.193] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:51.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:195:0) after 1m0s
[19:24:51.226] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:195:0)
[19:24:51.226] Timer tick, asking Buildbucket for the build status
[19:24:51.278] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:51.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:196:0) after 1m0s
[19:25:51.300] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:196:0)
[19:25:51.300] Timer tick, asking Buildbucket for the build status
[19:25:51.451] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:51.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:197:0) after 1m0s
[19:26:51.476] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:197:0)
[19:26:51.476] Timer tick, asking Buildbucket for the build status
[19:26:51.576] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:51.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:198:0) after 1m0s
[19:27:51.597] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:198:0)
[19:27:51.597] Timer tick, asking Buildbucket for the build status
[19:27:51.646] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:51.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:199:0) after 1m0s
[19:28:51.670] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:199:0)
[19:28:51.670] Timer tick, asking Buildbucket for the build status
[19:28:51.763] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:51.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:200:0) after 1m0s
[19:29:51.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:200:0)
[19:29:51.786] Timer tick, asking Buildbucket for the build status
[19:29:52.010] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:52.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:201:0) after 1m0s
[19:30:52.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:201:0)
[19:30:52.029] Timer tick, asking Buildbucket for the build status
[19:30:52.124] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:52.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:202:0) after 1m0s
[19:31:52.147] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:202:0)
[19:31:52.147] Timer tick, asking Buildbucket for the build status
[19:31:52.324] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:52.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:203:0) after 1m0s
[19:32:52.357] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:203:0)
[19:32:52.357] Timer tick, asking Buildbucket for the build status
[19:32:52.436] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:52.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:204:0) after 1m0s
[19:33:52.457] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:204:0)
[19:33:52.457] Timer tick, asking Buildbucket for the build status
[19:33:52.537] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:52.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:205:0) after 1m0s
[19:34:52.570] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:205:0)
[19:34:52.570] Timer tick, asking Buildbucket for the build status
[19:34:52.722] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:52.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:206:0) after 1m0s
[19:35:52.745] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:206:0)
[19:35:52.745] Timer tick, asking Buildbucket for the build status
[19:35:52.796] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:52.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:207:0) after 1m0s
[19:36:52.816] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:207:0)
[19:36:52.816] Timer tick, asking Buildbucket for the build status
[19:36:52.883] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:52.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:208:0) after 1m0s
[19:37:52.930] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:208:0)
[19:37:52.930] Timer tick, asking Buildbucket for the build status
[19:37:53.046] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:53.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:209:0) after 1m0s
[19:38:53.162] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:209:0)
[19:38:53.162] Timer tick, asking Buildbucket for the build status
[19:38:53.317] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:53.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:210:0) after 1m0s
[19:39:53.340] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:210:0)
[19:39:53.340] Timer tick, asking Buildbucket for the build status
[19:39:53.405] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:53.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:211:0) after 1m0s
[19:40:53.425] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:211:0)
[19:40:53.425] Timer tick, asking Buildbucket for the build status
[19:40:53.538] Build 8923616160065284560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:53.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089046222503533136:212:0) after 1m0s
[19:40:54.781] Received PubSub notification, asking Buildbucket for the build status
[19:40:54.818] Build 8923616160065284560: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:40:54.818] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1548186053609632",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548173480885561",
    "failure_reason": "BUILD_FAILURE",
    "id": "8923616160065284560",
    "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\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure predownload validator corpus\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"0\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2670\"], \"server_version\": [\"4012-9647885\"], \"machine_type\": [\"n1-highcpu-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"vm316-m1\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"builder_30037600afb0fc8938a6d537e3c45a46491e7159df17e0dae8492f75116b87ca_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_78893cbd4c292ba203eb7a045a56252df7f14bd0ad3b7b91fac8bfee09f12c2b_v2\", \"builder_7ea55abfea25d314356d803a5f0ad62170c3dfd6004ffb0ffd94b75873d1ce3b_v2\", \"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"builder_fa9d879aabe313024a0ba592a6a7867dc3149ebafcc9bdcf1ac65c59a572870d_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 97, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1548173480885561, \"tags\": [\"builder:linux-64-validator-opt\", \"buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089046222503533136\", \"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\": \"8923616160065284560\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"bot_id\": \"vm316-m1\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1548175022369234",
    "status": "COMPLETED",
    "status_changed_ts": "1548186054535892",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/97",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089046222503533136",
      "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/97",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8923616160065284560",
      "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/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "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/8923616160065284560/+/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:9089046222503533136",
      "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:4122e711faf74a549260c6de6a369a8fe79a9d3e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:428f2620dd363510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548186054539378",
    "url": "https://ci.chromium.org/b/8923616160065284560",
    "utcnow_ts": "1548186054809926"
  }
}
[19:40:54.818] Invocation finished in 3h29m35.641835038s with status FAILED