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

• 84d6fbfa44f1de95e21107766fc35fa1f6ac0897 (6 years ago)

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

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

[00:50:09.034] New invocation is queued and will start shortly
[00:50:10.490] Starting the invocation (attempt 1)
[00:50:10.520] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:50:10.520] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:50:10.520] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9089466565337123872",
  "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\":\"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
  "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:9089466565337123872",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
    "gitiles_ref:refs/heads/master"
  ]
}
[00:50:12.231] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547772610594814",
    "id": "8924036503031919120",
    "lease_expiration_ts": "1547923811728116",
    "lease_key": "1325315522",
    "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\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1547772612033987",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/95",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089466565337123872",
      "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/95",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8924036503031919120",
      "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/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "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/8924036503031919120/+/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:9089466565337123872",
      "swarming_tag:scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_tag:service_account:nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:427741522ab44b10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547772612034572",
    "url": "https://ci.chromium.org/b/8924036503031919120",
    "utcnow_ts": "1547772612217010"
  }
}
[00:50:12.231] Task URL: https://ci.chromium.org/b/8924036503031919120
[00:50:12.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:2:0) after 1m0s
[00:51:12.295] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:2:0)
[00:51:12.295] Timer tick, asking Buildbucket for the build status
[00:51:12.367] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:12.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:3:0) after 1m0s
[00:52:12.349] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:3:0)
[00:52:12.349] Timer tick, asking Buildbucket for the build status
[00:52:12.392] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:12.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:4:0) after 1m0s
[00:53:12.411] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:4:0)
[00:53:12.411] Timer tick, asking Buildbucket for the build status
[00:53:12.457] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:12.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:5:0) after 1m0s
[00:54:12.487] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:5:0)
[00:54:12.487] Timer tick, asking Buildbucket for the build status
[00:54:12.623] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:12.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:6:0) after 1m0s
[00:55:12.638] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:6:0)
[00:55:12.638] Timer tick, asking Buildbucket for the build status
[00:55:12.687] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:12.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:7:0) after 1m0s
[00:56:12.706] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:7:0)
[00:56:12.706] Timer tick, asking Buildbucket for the build status
[00:56:12.842] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:12.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:8:0) after 1m0s
[00:57:12.857] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:8:0)
[00:57:12.857] Timer tick, asking Buildbucket for the build status
[00:57:12.889] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:12.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:9:0) after 1m0s
[00:58:12.913] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:9:0)
[00:58:12.913] Timer tick, asking Buildbucket for the build status
[00:58:13.028] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:13.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:10:0) after 1m0s
[00:59:13.052] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:10:0)
[00:59:13.052] Timer tick, asking Buildbucket for the build status
[00:59:13.135] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:13.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:11:0) after 1m0s
[01:00:13.159] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:11:0)
[01:00:13.159] Timer tick, asking Buildbucket for the build status
[01:00:13.281] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:13.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:12:0) after 1m0s
[01:01:13.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:12:0)
[01:01:13.298] Timer tick, asking Buildbucket for the build status
[01:01:13.534] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:13.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:13:0) after 1m0s
[01:02:13.551] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:13:0)
[01:02:13.551] Timer tick, asking Buildbucket for the build status
[01:02:13.618] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:13.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:14:0) after 1m0s
[01:03:13.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:14:0)
[01:03:13.640] Timer tick, asking Buildbucket for the build status
[01:03:13.684] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:13.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:15:0) after 1m0s
[01:04:13.706] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:15:0)
[01:04:13.706] Timer tick, asking Buildbucket for the build status
[01:04:13.759] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:13.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:16:0) after 1m0s
[01:05:13.775] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:16:0)
[01:05:13.775] Timer tick, asking Buildbucket for the build status
[01:05:13.818] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:13.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:17:0) after 1m0s
[01:06:13.838] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:17:0)
[01:06:13.838] Timer tick, asking Buildbucket for the build status
[01:06:14.001] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:14.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:18:0) after 1m0s
[01:07:14.022] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:18:0)
[01:07:14.022] Timer tick, asking Buildbucket for the build status
[01:07:14.057] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:14.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:19:0) after 1m0s
[01:08:14.074] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:19:0)
[01:08:14.074] Timer tick, asking Buildbucket for the build status
[01:08:14.231] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:14.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:20:0) after 1m0s
[01:09:14.248] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:20:0)
[01:09:14.248] Timer tick, asking Buildbucket for the build status
[01:09:14.307] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:14.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:21:0) after 1m0s
[01:10:14.362] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:21:0)
[01:10:14.362] Timer tick, asking Buildbucket for the build status
[01:10:14.405] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:14.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:22:0) after 1m0s
[01:11:14.425] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:22:0)
[01:11:14.425] Timer tick, asking Buildbucket for the build status
[01:11:14.463] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:14.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:23:0) after 1m0s
[01:12:14.506] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:23:0)
[01:12:14.506] Timer tick, asking Buildbucket for the build status
[01:12:14.543] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:14.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:24:0) after 1m0s
[01:13:14.560] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:24:0)
[01:13:14.560] Timer tick, asking Buildbucket for the build status
[01:13:14.624] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:14.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:25:0) after 1m0s
[01:14:14.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:25:0)
[01:14:14.640] Timer tick, asking Buildbucket for the build status
[01:14:14.721] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:14.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:26:0) after 1m0s
[01:15:14.746] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:26:0)
[01:15:14.746] Timer tick, asking Buildbucket for the build status
[01:15:14.801] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:14.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:27:0) after 1m0s
[01:16:14.817] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:27:0)
[01:16:14.817] Timer tick, asking Buildbucket for the build status
[01:16:14.877] Build 8924036503031919120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:14.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:28:0) after 1m0s
[01:17:12.504] Received PubSub notification, asking Buildbucket for the build status
[01:17:12.546] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:14.899] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:28:0)
[01:17:14.899] Timer tick, asking Buildbucket for the build status
[01:17:14.951] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:14.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:30:0) after 1m0s
[01:18:15.069] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:30:0)
[01:18:15.069] Timer tick, asking Buildbucket for the build status
[01:18:15.153] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:15.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:31:0) after 1m0s
[01:19:15.174] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:31:0)
[01:19:15.174] Timer tick, asking Buildbucket for the build status
[01:19:15.209] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:15.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:32:0) after 1m0s
[01:20:15.257] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:32:0)
[01:20:15.257] Timer tick, asking Buildbucket for the build status
[01:20:15.350] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:15.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:33:0) after 1m0s
[01:21:15.369] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:33:0)
[01:21:15.369] Timer tick, asking Buildbucket for the build status
[01:21:15.446] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:15.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:34:0) after 1m0s
[01:22:15.467] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:34:0)
[01:22:15.467] Timer tick, asking Buildbucket for the build status
[01:22:15.510] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:15.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:35:0) after 1m0s
[01:23:15.531] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:35:0)
[01:23:15.531] Timer tick, asking Buildbucket for the build status
[01:23:15.570] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:15.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:36:0) after 1m0s
[01:24:15.595] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:36:0)
[01:24:15.595] Timer tick, asking Buildbucket for the build status
[01:24:15.740] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:15.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:37:0) after 1m0s
[01:25:15.758] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:37:0)
[01:25:15.758] Timer tick, asking Buildbucket for the build status
[01:25:15.815] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:15.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:38:0) after 1m0s
[01:26:15.845] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:38:0)
[01:26:15.845] Timer tick, asking Buildbucket for the build status
[01:26:15.887] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:15.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:39:0) after 1m0s
[01:27:15.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:39:0)
[01:27:15.907] Timer tick, asking Buildbucket for the build status
[01:27:15.943] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:40:0) after 1m0s
[01:28:15.966] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:40:0)
[01:28:15.966] Timer tick, asking Buildbucket for the build status
[01:28:16.014] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:16.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:41:0) after 1m0s
[01:29:16.072] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:41:0)
[01:29:16.072] Timer tick, asking Buildbucket for the build status
[01:29:16.116] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:16.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:42:0) after 1m0s
[01:30:16.135] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:42:0)
[01:30:16.135] Timer tick, asking Buildbucket for the build status
[01:30:16.178] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:16.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:43:0) after 1m0s
[01:31:16.194] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:43:0)
[01:31:16.194] Timer tick, asking Buildbucket for the build status
[01:31:16.235] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:16.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:44:0) after 1m0s
[01:32:16.256] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:44:0)
[01:32:16.256] Timer tick, asking Buildbucket for the build status
[01:32:16.299] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:16.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:45:0) after 1m0s
[01:33:16.318] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:45:0)
[01:33:16.318] Timer tick, asking Buildbucket for the build status
[01:33:16.378] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:16.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:46:0) after 1m0s
[01:34:16.395] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:46:0)
[01:34:16.395] Timer tick, asking Buildbucket for the build status
[01:34:16.461] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:16.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:47:0) after 1m0s
[01:35:16.622] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:47:0)
[01:35:16.622] Timer tick, asking Buildbucket for the build status
[01:35:16.668] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:16.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:48:0) after 1m0s
[01:36:16.685] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:48:0)
[01:36:16.685] Timer tick, asking Buildbucket for the build status
[01:36:16.725] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:16.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:49:0) after 1m0s
[01:37:16.746] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:49:0)
[01:37:16.746] Timer tick, asking Buildbucket for the build status
[01:37:16.921] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:16.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:50:0) after 1m0s
[01:38:16.940] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:50:0)
[01:38:16.940] Timer tick, asking Buildbucket for the build status
[01:38:16.977] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:16.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:51:0) after 1m0s
[01:39:16.997] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:51:0)
[01:39:16.997] Timer tick, asking Buildbucket for the build status
[01:39:17.042] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:17.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:52:0) after 1m0s
[01:40:17.061] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:52:0)
[01:40:17.061] Timer tick, asking Buildbucket for the build status
[01:40:17.176] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:17.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:53:0) after 1m0s
[01:41:17.194] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:53:0)
[01:41:17.194] Timer tick, asking Buildbucket for the build status
[01:41:17.250] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:17.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:54:0) after 1m0s
[01:42:17.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:54:0)
[01:42:17.352] Timer tick, asking Buildbucket for the build status
[01:42:17.424] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:17.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:55:0) after 1m0s
[01:43:17.446] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:55:0)
[01:43:17.446] Timer tick, asking Buildbucket for the build status
[01:43:17.480] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:17.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:56:0) after 1m0s
[01:44:17.535] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:56:0)
[01:44:17.535] Timer tick, asking Buildbucket for the build status
[01:44:17.616] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:17.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:57:0) after 1m0s
[01:45:17.634] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:57:0)
[01:45:17.634] Timer tick, asking Buildbucket for the build status
[01:45:17.708] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:17.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:58:0) after 1m0s
[01:46:17.728] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:58:0)
[01:46:17.728] Timer tick, asking Buildbucket for the build status
[01:46:17.890] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:17.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:59:0) after 1m0s
[01:47:17.909] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:59:0)
[01:47:17.909] Timer tick, asking Buildbucket for the build status
[01:47:18.017] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:18.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:60:0) after 1m0s
[01:48:18.038] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:60:0)
[01:48:18.038] Timer tick, asking Buildbucket for the build status
[01:48:18.106] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:18.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:61:0) after 1m0s
[01:49:18.128] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:61:0)
[01:49:18.128] Timer tick, asking Buildbucket for the build status
[01:49:18.162] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:18.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:62:0) after 1m0s
[01:50:18.182] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:62:0)
[01:50:18.182] Timer tick, asking Buildbucket for the build status
[01:50:18.254] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:18.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:63:0) after 1m0s
[01:51:18.271] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:63:0)
[01:51:18.271] Timer tick, asking Buildbucket for the build status
[01:51:18.334] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:18.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:64:0) after 1m0s
[01:52:18.405] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:64:0)
[01:52:18.405] Timer tick, asking Buildbucket for the build status
[01:52:18.443] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:18.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:65:0) after 1m0s
[01:53:18.507] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:65:0)
[01:53:18.507] Timer tick, asking Buildbucket for the build status
[01:53:18.611] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:18.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:66:0) after 1m0s
[01:54:18.631] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:66:0)
[01:54:18.631] Timer tick, asking Buildbucket for the build status
[01:54:18.677] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:18.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:67:0) after 1m0s
[01:55:18.695] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:67:0)
[01:55:18.695] Timer tick, asking Buildbucket for the build status
[01:55:18.762] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:18.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:68:0) after 1m0s
[01:56:18.783] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:68:0)
[01:56:18.783] Timer tick, asking Buildbucket for the build status
[01:56:18.837] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:18.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:69:0) after 1m0s
[01:57:18.916] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:69:0)
[01:57:18.916] Timer tick, asking Buildbucket for the build status
[01:57:19.072] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:19.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:70:0) after 1m0s
[01:58:19.093] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:70:0)
[01:58:19.093] Timer tick, asking Buildbucket for the build status
[01:58:19.129] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:19.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:71:0) after 1m0s
[01:59:19.146] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:71:0)
[01:59:19.146] Timer tick, asking Buildbucket for the build status
[01:59:19.195] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:19.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:72:0) after 1m0s
[02:00:19.287] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:72:0)
[02:00:19.287] Timer tick, asking Buildbucket for the build status
[02:00:19.319] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:19.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:73:0) after 1m0s
[02:01:19.349] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:73:0)
[02:01:19.349] Timer tick, asking Buildbucket for the build status
[02:01:19.389] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:19.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:74:0) after 1m0s
[02:02:19.451] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:74:0)
[02:02:19.452] Timer tick, asking Buildbucket for the build status
[02:02:19.492] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:19.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:75:0) after 1m0s
[02:03:19.513] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:75:0)
[02:03:19.513] Timer tick, asking Buildbucket for the build status
[02:03:19.620] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:19.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:76:0) after 1m0s
[02:04:19.680] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:76:0)
[02:04:19.680] Timer tick, asking Buildbucket for the build status
[02:04:19.728] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:19.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:77:0) after 1m0s
[02:05:19.820] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:77:0)
[02:05:19.820] Timer tick, asking Buildbucket for the build status
[02:05:19.887] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:19.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:78:0) after 1m0s
[02:06:19.909] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:78:0)
[02:06:19.909] Timer tick, asking Buildbucket for the build status
[02:06:19.945] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:19.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:79:0) after 1m0s
[02:07:19.977] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:79:0)
[02:07:19.977] Timer tick, asking Buildbucket for the build status
[02:07:20.071] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:20.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:80:0) after 1m0s
[02:08:20.092] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:80:0)
[02:08:20.092] Timer tick, asking Buildbucket for the build status
[02:08:20.144] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:20.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:81:0) after 1m0s
[02:09:20.163] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:81:0)
[02:09:20.163] Timer tick, asking Buildbucket for the build status
[02:09:20.224] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:20.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:82:0) after 1m0s
[02:10:20.241] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:82:0)
[02:10:20.241] Timer tick, asking Buildbucket for the build status
[02:10:20.276] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:20.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:83:0) after 1m0s
[02:11:20.293] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:83:0)
[02:11:20.293] Timer tick, asking Buildbucket for the build status
[02:11:20.342] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:20.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:84:0) after 1m0s
[02:12:20.414] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:84:0)
[02:12:20.414] Timer tick, asking Buildbucket for the build status
[02:12:20.496] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:20.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:85:0) after 1m0s
[02:13:20.516] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:85:0)
[02:13:20.516] Timer tick, asking Buildbucket for the build status
[02:13:20.550] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:20.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:86:0) after 1m0s
[02:14:20.576] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:86:0)
[02:14:20.576] Timer tick, asking Buildbucket for the build status
[02:14:20.754] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:20.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:87:0) after 1m0s
[02:15:20.774] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:87:0)
[02:15:20.774] Timer tick, asking Buildbucket for the build status
[02:15:20.832] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:20.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:88:0) after 1m0s
[02:16:20.850] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:88:0)
[02:16:20.850] Timer tick, asking Buildbucket for the build status
[02:16:20.902] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:20.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:89:0) after 1m0s
[02:17:20.922] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:89:0)
[02:17:20.922] Timer tick, asking Buildbucket for the build status
[02:17:21.428] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:21.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:90:0) after 1m0s
[02:18:21.447] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:90:0)
[02:18:21.447] Timer tick, asking Buildbucket for the build status
[02:18:21.758] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:21.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:91:0) after 1m0s
[02:19:21.779] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:91:0)
[02:19:21.779] Timer tick, asking Buildbucket for the build status
[02:19:21.860] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:21.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:92:0) after 1m0s
[02:20:21.877] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:92:0)
[02:20:21.877] Timer tick, asking Buildbucket for the build status
[02:20:21.910] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:21.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:93:0) after 1m0s
[02:21:21.928] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:93:0)
[02:21:21.928] Timer tick, asking Buildbucket for the build status
[02:21:21.977] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:21.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:94:0) after 1m0s
[02:22:22.002] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:94:0)
[02:22:22.002] Timer tick, asking Buildbucket for the build status
[02:22:22.032] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:22.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:95:0) after 1m0s
[02:23:22.054] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:95:0)
[02:23:22.054] Timer tick, asking Buildbucket for the build status
[02:23:22.107] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:22.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:96:0) after 1m0s
[02:24:22.161] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:96:0)
[02:24:22.161] Timer tick, asking Buildbucket for the build status
[02:24:22.211] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:22.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:97:0) after 1m0s
[02:25:22.231] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:97:0)
[02:25:22.231] Timer tick, asking Buildbucket for the build status
[02:25:22.289] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:22.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:98:0) after 1m0s
[02:26:22.309] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:98:0)
[02:26:22.309] Timer tick, asking Buildbucket for the build status
[02:26:22.352] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:22.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:99:0) after 1m0s
[02:27:22.372] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:99:0)
[02:27:22.372] Timer tick, asking Buildbucket for the build status
[02:27:22.534] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:22.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:100:0) after 1m0s
[02:28:22.554] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:100:0)
[02:28:22.554] Timer tick, asking Buildbucket for the build status
[02:28:22.586] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:22.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:101:0) after 1m0s
[02:29:22.620] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:101:0)
[02:29:22.620] Timer tick, asking Buildbucket for the build status
[02:29:22.739] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:22.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:102:0) after 1m0s
[02:30:22.763] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:102:0)
[02:30:22.763] Timer tick, asking Buildbucket for the build status
[02:30:22.806] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:22.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:103:0) after 1m0s
[02:31:22.826] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:103:0)
[02:31:22.826] Timer tick, asking Buildbucket for the build status
[02:31:22.883] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:22.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:104:0) after 1m0s
[02:32:22.910] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:104:0)
[02:32:22.910] Timer tick, asking Buildbucket for the build status
[02:32:23.003] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:23.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:105:0) after 1m0s
[02:33:23.027] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:105:0)
[02:33:23.027] Timer tick, asking Buildbucket for the build status
[02:33:23.089] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:23.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:106:0) after 1m0s
[02:34:23.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:106:0)
[02:34:23.113] Timer tick, asking Buildbucket for the build status
[02:34:23.150] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:23.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:107:0) after 1m0s
[02:35:23.190] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:107:0)
[02:35:23.190] Timer tick, asking Buildbucket for the build status
[02:35:23.238] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:23.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:108:0) after 1m0s
[02:36:23.259] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:108:0)
[02:36:23.259] Timer tick, asking Buildbucket for the build status
[02:36:23.323] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:23.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:109:0) after 1m0s
[02:37:23.344] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:109:0)
[02:37:23.344] Timer tick, asking Buildbucket for the build status
[02:37:23.501] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:23.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:110:0) after 1m0s
[02:38:23.529] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:110:0)
[02:38:23.529] Timer tick, asking Buildbucket for the build status
[02:38:23.624] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:23.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:111:0) after 1m0s
[02:39:23.644] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:111:0)
[02:39:23.644] Timer tick, asking Buildbucket for the build status
[02:39:23.707] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:112:0) after 1m0s
[02:40:23.725] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:112:0)
[02:40:23.725] Timer tick, asking Buildbucket for the build status
[02:40:24.044] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:24.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:113:0) after 1m0s
[02:41:24.063] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:113:0)
[02:41:24.063] Timer tick, asking Buildbucket for the build status
[02:41:24.262] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:24.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:114:0) after 1m0s
[02:42:24.288] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:114:0)
[02:42:24.288] Timer tick, asking Buildbucket for the build status
[02:42:24.394] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:24.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:115:0) after 1m0s
[02:43:24.414] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:115:0)
[02:43:24.414] Timer tick, asking Buildbucket for the build status
[02:43:24.456] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:24.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:116:0) after 1m0s
[02:44:24.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:116:0)
[02:44:24.493] Timer tick, asking Buildbucket for the build status
[02:44:24.558] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:24.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:117:0) after 1m0s
[02:45:24.599] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:117:0)
[02:45:24.599] Timer tick, asking Buildbucket for the build status
[02:45:24.672] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:24.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:118:0) after 1m0s
[02:46:24.694] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:118:0)
[02:46:24.694] Timer tick, asking Buildbucket for the build status
[02:46:24.741] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:24.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:119:0) after 1m0s
[02:47:24.767] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:119:0)
[02:47:24.767] Timer tick, asking Buildbucket for the build status
[02:47:25.011] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:25.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:120:0) after 1m0s
[02:48:25.034] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:120:0)
[02:48:25.034] Timer tick, asking Buildbucket for the build status
[02:48:25.099] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:25.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:121:0) after 1m0s
[02:49:25.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:121:0)
[02:49:25.123] Timer tick, asking Buildbucket for the build status
[02:49:25.184] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:25.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:122:0) after 1m0s
[02:50:25.211] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:122:0)
[02:50:25.211] Timer tick, asking Buildbucket for the build status
[02:50:25.282] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:25.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:123:0) after 1m0s
[02:51:25.326] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:123:0)
[02:51:25.326] Timer tick, asking Buildbucket for the build status
[02:51:25.441] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:25.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:124:0) after 1m0s
[02:52:25.463] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:124:0)
[02:52:25.463] Timer tick, asking Buildbucket for the build status
[02:52:25.571] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:25.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:125:0) after 1m0s
[02:53:25.594] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:125:0)
[02:53:25.594] Timer tick, asking Buildbucket for the build status
[02:53:25.655] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:25.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:126:0) after 1m0s
[02:54:25.678] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:126:0)
[02:54:25.678] Timer tick, asking Buildbucket for the build status
[02:54:25.754] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:127:0) after 1m0s
[02:55:25.795] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:127:0)
[02:55:25.795] Timer tick, asking Buildbucket for the build status
[02:55:25.932] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:128:0) after 1m0s
[02:56:25.953] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:128:0)
[02:56:25.953] Timer tick, asking Buildbucket for the build status
[02:56:26.039] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:26.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:129:0) after 1m0s
[02:57:26.099] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:129:0)
[02:57:26.099] Timer tick, asking Buildbucket for the build status
[02:57:26.165] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:26.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:130:0) after 1m0s
[02:58:26.193] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:130:0)
[02:58:26.193] Timer tick, asking Buildbucket for the build status
[02:58:26.240] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:131:0) after 1m0s
[02:59:26.265] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:131:0)
[02:59:26.265] Timer tick, asking Buildbucket for the build status
[02:59:26.328] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:132:0) after 1m0s
[03:00:26.349] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:132:0)
[03:00:26.349] Timer tick, asking Buildbucket for the build status
[03:00:26.420] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:26.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:133:0) after 1m0s
[03:01:26.446] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:133:0)
[03:01:26.446] Timer tick, asking Buildbucket for the build status
[03:01:26.782] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:26.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:134:0) after 1m0s
[03:02:26.809] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:134:0)
[03:02:26.809] Timer tick, asking Buildbucket for the build status
[03:02:26.855] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:26.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:135:0) after 1m0s
[03:03:26.916] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:135:0)
[03:03:26.916] Timer tick, asking Buildbucket for the build status
[03:03:26.986] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:26.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:136:0) after 1m0s
[03:04:27.008] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:136:0)
[03:04:27.008] Timer tick, asking Buildbucket for the build status
[03:04:27.156] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:27.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:137:0) after 1m0s
[03:05:27.176] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:137:0)
[03:05:27.176] Timer tick, asking Buildbucket for the build status
[03:05:27.258] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:27.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:138:0) after 1m0s
[03:06:27.360] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:138:0)
[03:06:27.360] Timer tick, asking Buildbucket for the build status
[03:06:27.452] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:139:0) after 1m0s
[03:07:27.477] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:139:0)
[03:07:27.477] Timer tick, asking Buildbucket for the build status
[03:07:27.774] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:27.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:140:0) after 1m0s
[03:08:27.797] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:140:0)
[03:08:27.797] Timer tick, asking Buildbucket for the build status
[03:08:27.891] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:27.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:141:0) after 1m0s
[03:09:27.911] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:141:0)
[03:09:27.911] Timer tick, asking Buildbucket for the build status
[03:09:28.013] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:28.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:142:0) after 1m0s
[03:10:28.076] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:142:0)
[03:10:28.076] Timer tick, asking Buildbucket for the build status
[03:10:28.149] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:28.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:143:0) after 1m0s
[03:11:28.172] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:143:0)
[03:11:28.172] Timer tick, asking Buildbucket for the build status
[03:11:28.218] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:28.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:144:0) after 1m0s
[03:12:28.248] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:144:0)
[03:12:28.248] Timer tick, asking Buildbucket for the build status
[03:12:28.321] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:28.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:145:0) after 1m0s
[03:13:28.348] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:145:0)
[03:13:28.348] Timer tick, asking Buildbucket for the build status
[03:13:28.397] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:28.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:146:0) after 1m0s
[03:14:28.420] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:146:0)
[03:14:28.420] Timer tick, asking Buildbucket for the build status
[03:14:28.513] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:28.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:147:0) after 1m0s
[03:15:28.541] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:147:0)
[03:15:28.541] Timer tick, asking Buildbucket for the build status
[03:15:28.638] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:28.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:148:0) after 1m0s
[03:16:28.661] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:148:0)
[03:16:28.661] Timer tick, asking Buildbucket for the build status
[03:16:28.817] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:28.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:149:0) after 1m0s
[03:17:28.835] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:149:0)
[03:17:28.835] Timer tick, asking Buildbucket for the build status
[03:17:28.886] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:28.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:150:0) after 1m0s
[03:18:28.937] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:150:0)
[03:18:28.937] Timer tick, asking Buildbucket for the build status
[03:18:28.986] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:28.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:151:0) after 1m0s
[03:19:29.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:151:0)
[03:19:29.029] Timer tick, asking Buildbucket for the build status
[03:19:29.124] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:29.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:152:0) after 1m0s
[03:20:29.146] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:152:0)
[03:20:29.146] Timer tick, asking Buildbucket for the build status
[03:20:29.193] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:29.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:153:0) after 1m0s
[03:21:29.219] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:153:0)
[03:21:29.219] Timer tick, asking Buildbucket for the build status
[03:21:29.324] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:29.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:154:0) after 1m0s
[03:22:29.426] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:154:0)
[03:22:29.426] Timer tick, asking Buildbucket for the build status
[03:22:29.629] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:29.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:155:0) after 1m0s
[03:23:29.736] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:155:0)
[03:23:29.736] Timer tick, asking Buildbucket for the build status
[03:23:29.805] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:29.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:156:0) after 1m0s
[03:24:29.824] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:156:0)
[03:24:29.824] Timer tick, asking Buildbucket for the build status
[03:24:29.887] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:29.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:157:0) after 1m0s
[03:25:29.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:157:0)
[03:25:29.907] Timer tick, asking Buildbucket for the build status
[03:25:30.023] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:30.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:158:0) after 1m0s
[03:26:30.059] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:158:0)
[03:26:30.059] Timer tick, asking Buildbucket for the build status
[03:26:30.145] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:30.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:159:0) after 1m0s
[03:27:30.165] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:159:0)
[03:27:30.165] Timer tick, asking Buildbucket for the build status
[03:27:30.314] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:30.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:160:0) after 1m0s
[03:28:30.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:160:0)
[03:28:30.352] Timer tick, asking Buildbucket for the build status
[03:28:30.525] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:30.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:161:0) after 1m0s
[03:29:30.545] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:161:0)
[03:29:30.545] Timer tick, asking Buildbucket for the build status
[03:29:30.600] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:30.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:162:0) after 1m0s
[03:30:30.626] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:162:0)
[03:30:30.626] Timer tick, asking Buildbucket for the build status
[03:30:30.703] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:30.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:163:0) after 1m0s
[03:31:30.724] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:163:0)
[03:31:30.724] Timer tick, asking Buildbucket for the build status
[03:31:30.825] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:30.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:164:0) after 1m0s
[03:32:30.853] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:164:0)
[03:32:30.853] Timer tick, asking Buildbucket for the build status
[03:32:30.941] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:30.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:165:0) after 1m0s
[03:33:30.968] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:165:0)
[03:33:30.968] Timer tick, asking Buildbucket for the build status
[03:33:31.013] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:31.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:166:0) after 1m0s
[03:34:31.046] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:166:0)
[03:34:31.046] Timer tick, asking Buildbucket for the build status
[03:34:31.160] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:31.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:167:0) after 1m0s
[03:35:31.181] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:167:0)
[03:35:31.181] Timer tick, asking Buildbucket for the build status
[03:35:31.226] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:31.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:168:0) after 1m0s
[03:36:31.383] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:168:0)
[03:36:31.383] Timer tick, asking Buildbucket for the build status
[03:36:31.453] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:31.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:169:0) after 1m0s
[03:37:31.602] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:169:0)
[03:37:31.602] Timer tick, asking Buildbucket for the build status
[03:37:31.682] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:31.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:170:0) after 1m0s
[03:38:31.742] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:170:0)
[03:38:31.742] Timer tick, asking Buildbucket for the build status
[03:38:31.967] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:31.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:171:0) after 1m0s
[03:39:32.003] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:171:0)
[03:39:32.003] Timer tick, asking Buildbucket for the build status
[03:39:32.054] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:32.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:172:0) after 1m0s
[03:40:32.084] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:172:0)
[03:40:32.084] Timer tick, asking Buildbucket for the build status
[03:40:32.241] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:32.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:173:0) after 1m0s
[03:41:32.268] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:173:0)
[03:41:32.268] Timer tick, asking Buildbucket for the build status
[03:41:32.359] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:32.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:174:0) after 1m0s
[03:42:32.385] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:174:0)
[03:42:32.385] Timer tick, asking Buildbucket for the build status
[03:42:32.498] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:32.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:175:0) after 1m0s
[03:43:32.525] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:175:0)
[03:43:32.525] Timer tick, asking Buildbucket for the build status
[03:43:32.656] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:32.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:176:0) after 1m0s
[03:44:32.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:176:0)
[03:44:32.679] Timer tick, asking Buildbucket for the build status
[03:44:32.846] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:32.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:177:0) after 1m0s
[03:45:32.873] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:177:0)
[03:45:32.873] Timer tick, asking Buildbucket for the build status
[03:45:33.006] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:33.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:178:0) after 1m0s
[03:46:33.031] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:178:0)
[03:46:33.031] Timer tick, asking Buildbucket for the build status
[03:46:33.083] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:33.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:179:0) after 1m0s
[03:47:33.107] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:179:0)
[03:47:33.107] Timer tick, asking Buildbucket for the build status
[03:47:33.175] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:180:0) after 1m0s
[03:48:33.247] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:180:0)
[03:48:33.247] Timer tick, asking Buildbucket for the build status
[03:48:33.379] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:33.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:181:0) after 1m0s
[03:49:33.412] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:181:0)
[03:49:33.412] Timer tick, asking Buildbucket for the build status
[03:49:33.496] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:33.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:182:0) after 1m0s
[03:50:33.521] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:182:0)
[03:50:33.521] Timer tick, asking Buildbucket for the build status
[03:50:33.565] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:33.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:183:0) after 1m0s
[03:51:33.593] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:183:0)
[03:51:33.593] Timer tick, asking Buildbucket for the build status
[03:51:33.684] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:33.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:184:0) after 1m0s
[03:52:33.714] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:184:0)
[03:52:33.714] Timer tick, asking Buildbucket for the build status
[03:52:33.781] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:33.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:185:0) after 1m0s
[03:53:33.808] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:185:0)
[03:53:33.808] Timer tick, asking Buildbucket for the build status
[03:53:33.943] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:33.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:186:0) after 1m0s
[03:54:33.970] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:186:0)
[03:54:33.970] Timer tick, asking Buildbucket for the build status
[03:54:34.123] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:34.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:187:0) after 1m0s
[03:55:34.151] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:187:0)
[03:55:34.151] Timer tick, asking Buildbucket for the build status
[03:55:34.197] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:34.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:188:0) after 1m0s
[03:56:34.222] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:188:0)
[03:56:34.222] Timer tick, asking Buildbucket for the build status
[03:56:34.269] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:34.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:189:0) after 1m0s
[03:57:34.295] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:189:0)
[03:57:34.295] Timer tick, asking Buildbucket for the build status
[03:57:34.501] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:34.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:190:0) after 1m0s
[03:58:34.536] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:190:0)
[03:58:34.536] Timer tick, asking Buildbucket for the build status
[03:58:34.581] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:34.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:191:0) after 1m0s
[03:59:34.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:191:0)
[03:59:34.606] Timer tick, asking Buildbucket for the build status
[03:59:34.657] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:34.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:192:0) after 1m0s
[04:00:34.710] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:192:0)
[04:00:34.710] Timer tick, asking Buildbucket for the build status
[04:00:34.755] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:34.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:193:0) after 1m0s
[04:01:34.780] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:193:0)
[04:01:34.780] Timer tick, asking Buildbucket for the build status
[04:01:34.844] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:34.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:194:0) after 1m0s
[04:02:34.874] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:194:0)
[04:02:34.874] Timer tick, asking Buildbucket for the build status
[04:02:34.957] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:34.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:195:0) after 1m0s
[04:03:34.984] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:195:0)
[04:03:34.984] Timer tick, asking Buildbucket for the build status
[04:03:35.060] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:35.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:196:0) after 1m0s
[04:04:35.089] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:196:0)
[04:04:35.089] Timer tick, asking Buildbucket for the build status
[04:04:35.171] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:35.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:197:0) after 1m0s
[04:05:35.204] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:197:0)
[04:05:35.204] Timer tick, asking Buildbucket for the build status
[04:05:35.447] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:35.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:198:0) after 1m0s
[04:06:35.472] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:198:0)
[04:06:35.472] Timer tick, asking Buildbucket for the build status
[04:06:35.544] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:35.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:199:0) after 1m0s
[04:07:35.582] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:199:0)
[04:07:35.582] Timer tick, asking Buildbucket for the build status
[04:07:35.633] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:35.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:200:0) after 1m0s
[04:08:35.661] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:200:0)
[04:08:35.661] Timer tick, asking Buildbucket for the build status
[04:08:35.828] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:35.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:201:0) after 1m0s
[04:09:35.855] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:201:0)
[04:09:35.855] Timer tick, asking Buildbucket for the build status
[04:09:36.144] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:36.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:202:0) after 1m0s
[04:10:36.177] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:202:0)
[04:10:36.177] Timer tick, asking Buildbucket for the build status
[04:10:36.242] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:36.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:203:0) after 1m0s
[04:11:36.265] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:203:0)
[04:11:36.265] Timer tick, asking Buildbucket for the build status
[04:11:36.330] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:36.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:204:0) after 1m0s
[04:12:36.385] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:204:0)
[04:12:36.385] Timer tick, asking Buildbucket for the build status
[04:12:36.606] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:36.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:205:0) after 1m0s
[04:13:36.635] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:205:0)
[04:13:36.635] Timer tick, asking Buildbucket for the build status
[04:13:36.741] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:36.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:206:0) after 1m0s
[04:14:36.764] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:206:0)
[04:14:36.764] Timer tick, asking Buildbucket for the build status
[04:14:36.842] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:36.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:207:0) after 1m0s
[04:15:36.885] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:207:0)
[04:15:36.885] Timer tick, asking Buildbucket for the build status
[04:15:37.148] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:37.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:208:0) after 1m0s
[04:16:37.187] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:208:0)
[04:16:37.187] Timer tick, asking Buildbucket for the build status
[04:16:37.318] Build 8924036503031919120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:37.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089466565337123872:209:0) after 1m0s
[04:17:05.890] Received PubSub notification, asking Buildbucket for the build status
[04:17:05.942] Build 8924036503031919120: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:17:05.942] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1547785024597849",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547772610594814",
    "failure_reason": "BUILD_FAILURE",
    "id": "8924036503031919120",
    "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\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
    "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\": [\"4004-c99644c\"], \"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\", \"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\": 95, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547772610594814, \"tags\": [\"builder:linux-64-validator-opt\", \"buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089466565337123872\", \"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\": \"8924036503031919120\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"bot_id\": \"vm316-m1\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1547774231845957",
    "status": "COMPLETED",
    "status_changed_ts": "1547785025305530",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/95",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089466565337123872",
      "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/95",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8924036503031919120",
      "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/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "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/8924036503031919120/+/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:9089466565337123872",
      "swarming_tag:scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_tag:service_account:nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:427741522ab44b10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547785025307671",
    "url": "https://ci.chromium.org/b/8924036503031919120",
    "utcnow_ts": "1547785025930186"
  }
}
[04:17:05.942] Invocation finished in 3h26m56.929397121s with status FAILED