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

Status: FAILED

Triggered by: -

Duration: 3 hours

Actions:

Definition (rev 8fecfaafc1abba4843234d58a712c5811f9e1de9)

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

Incoming triggers

1ea07c56ac9b57c30eb784ab2af582af0cdd4b08 (6 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[18:37:34.833] New invocation is queued and will start shortly
[18:37:36.300] Starting the invocation (attempt 1)
[18:37:36.353] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:37:36.353] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:37:36.353] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9090667765973908080",
  "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\":\"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
  "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:9090667765973908080",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
    "gitiles_ref:refs/heads/master"
  ]
}
[18:37:38.119] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1546627056452165",
    "id": "8925237703612064064",
    "lease_expiration_ts": "1546778257835619",
    "lease_key": "1218961357",
    "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\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1546627058000328",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/91",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9090667765973908080",
      "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/91",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8925237703612064064",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "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/8925237703612064064/+/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:9090667765973908080",
      "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:8df9132fd2891579a00ae465513e90e9036bee95",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4232f98f3037a310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1546627058000491",
    "url": "https://ci.chromium.org/b/8925237703612064064",
    "utcnow_ts": "1546627058098613"
  }
}
[18:37:38.119] Task URL: https://ci.chromium.org/b/8925237703612064064
[18:37:38.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:2:0) after 1m0s
[18:38:22.899] Received PubSub notification, asking Buildbucket for the build status
[18:38:22.953] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:38.839] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:2:0)
[18:38:38.839] Timer tick, asking Buildbucket for the build status
[18:38:38.980] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:38.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:4:0) after 1m0s
[18:39:39.284] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:4:0)
[18:39:39.284] Timer tick, asking Buildbucket for the build status
[18:39:39.410] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:39.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:5:0) after 1m0s
[18:40:39.434] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:5:0)
[18:40:39.434] Timer tick, asking Buildbucket for the build status
[18:40:39.569] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:39.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:6:0) after 1m0s
[18:41:39.631] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:6:0)
[18:41:39.631] Timer tick, asking Buildbucket for the build status
[18:41:39.775] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:39.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:7:0) after 1m0s
[18:42:40.175] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:7:0)
[18:42:40.175] Timer tick, asking Buildbucket for the build status
[18:42:40.312] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:40.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:8:0) after 1m0s
[18:43:40.332] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:8:0)
[18:43:40.332] Timer tick, asking Buildbucket for the build status
[18:43:40.432] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:40.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:9:0) after 1m0s
[18:44:40.465] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:9:0)
[18:44:40.465] Timer tick, asking Buildbucket for the build status
[18:44:40.579] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:40.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:10:0) after 1m0s
[18:45:40.603] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:10:0)
[18:45:40.603] Timer tick, asking Buildbucket for the build status
[18:45:40.670] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:40.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:11:0) after 1m0s
[18:46:40.688] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:11:0)
[18:46:40.688] Timer tick, asking Buildbucket for the build status
[18:46:40.758] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:40.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:12:0) after 1m0s
[18:47:40.938] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:12:0)
[18:47:40.938] Timer tick, asking Buildbucket for the build status
[18:47:41.106] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:41.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:13:0) after 1m0s
[18:48:41.170] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:13:0)
[18:48:41.170] Timer tick, asking Buildbucket for the build status
[18:48:41.247] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:41.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:14:0) after 1m0s
[18:49:41.265] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:14:0)
[18:49:41.265] Timer tick, asking Buildbucket for the build status
[18:49:41.322] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:41.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:15:0) after 1m0s
[18:50:41.475] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:15:0)
[18:50:41.475] Timer tick, asking Buildbucket for the build status
[18:50:41.548] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:41.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:16:0) after 1m0s
[18:51:41.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:16:0)
[18:51:41.568] Timer tick, asking Buildbucket for the build status
[18:51:41.646] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:41.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:17:0) after 1m0s
[18:52:41.666] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:17:0)
[18:52:41.666] Timer tick, asking Buildbucket for the build status
[18:52:41.729] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:41.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:18:0) after 1m0s
[18:53:41.748] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:18:0)
[18:53:41.748] Timer tick, asking Buildbucket for the build status
[18:53:41.786] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:41.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:19:0) after 1m0s
[18:54:41.807] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:19:0)
[18:54:41.807] Timer tick, asking Buildbucket for the build status
[18:54:41.846] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:41.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:20:0) after 1m0s
[18:55:41.866] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:20:0)
[18:55:41.866] Timer tick, asking Buildbucket for the build status
[18:55:41.894] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:41.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:21:0) after 1m0s
[18:56:41.910] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:21:0)
[18:56:41.910] Timer tick, asking Buildbucket for the build status
[18:56:41.941] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:41.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:22:0) after 1m0s
[18:57:41.957] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:22:0)
[18:57:41.957] Timer tick, asking Buildbucket for the build status
[18:57:42.059] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:42.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:23:0) after 1m0s
[18:58:42.078] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:23:0)
[18:58:42.078] Timer tick, asking Buildbucket for the build status
[18:58:42.122] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:42.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:24:0) after 1m0s
[18:59:42.168] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:24:0)
[18:59:42.168] Timer tick, asking Buildbucket for the build status
[18:59:42.231] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:42.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:25:0) after 1m0s
[19:00:42.264] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:25:0)
[19:00:42.264] Timer tick, asking Buildbucket for the build status
[19:00:42.303] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:42.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:26:0) after 1m0s
[19:01:42.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:26:0)
[19:01:42.325] Timer tick, asking Buildbucket for the build status
[19:01:42.365] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:42.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:27:0) after 1m0s
[19:02:42.385] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:27:0)
[19:02:42.385] Timer tick, asking Buildbucket for the build status
[19:02:42.501] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:42.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:28:0) after 1m0s
[19:03:42.518] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:28:0)
[19:03:42.518] Timer tick, asking Buildbucket for the build status
[19:03:42.595] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:42.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:29:0) after 1m0s
[19:04:42.620] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:29:0)
[19:04:42.620] Timer tick, asking Buildbucket for the build status
[19:04:42.673] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:42.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:30:0) after 1m0s
[19:05:42.703] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:30:0)
[19:05:42.703] Timer tick, asking Buildbucket for the build status
[19:05:42.806] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:42.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:31:0) after 1m0s
[19:06:42.840] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:31:0)
[19:06:42.840] Timer tick, asking Buildbucket for the build status
[19:06:42.877] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:42.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:32:0) after 1m0s
[19:07:42.897] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:32:0)
[19:07:42.898] Timer tick, asking Buildbucket for the build status
[19:07:42.950] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:42.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:33:0) after 1m0s
[19:08:42.969] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:33:0)
[19:08:42.969] Timer tick, asking Buildbucket for the build status
[19:08:43.006] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:43.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:34:0) after 1m0s
[19:09:43.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:34:0)
[19:09:43.029] Timer tick, asking Buildbucket for the build status
[19:09:43.103] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:43.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:35:0) after 1m0s
[19:10:43.129] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:35:0)
[19:10:43.129] Timer tick, asking Buildbucket for the build status
[19:10:43.180] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:43.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:36:0) after 1m0s
[19:11:43.223] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:36:0)
[19:11:43.223] Timer tick, asking Buildbucket for the build status
[19:11:43.266] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:43.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:37:0) after 1m0s
[19:12:43.409] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:37:0)
[19:12:43.409] Timer tick, asking Buildbucket for the build status
[19:12:43.517] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:43.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:38:0) after 1m0s
[19:13:43.548] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:38:0)
[19:13:43.548] Timer tick, asking Buildbucket for the build status
[19:13:43.753] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:43.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:39:0) after 1m0s
[19:14:43.772] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:39:0)
[19:14:43.772] Timer tick, asking Buildbucket for the build status
[19:14:44.003] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:44.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:40:0) after 1m0s
[19:15:44.024] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:40:0)
[19:15:44.024] Timer tick, asking Buildbucket for the build status
[19:15:44.096] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:44.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:41:0) after 1m0s
[19:16:44.120] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:41:0)
[19:16:44.120] Timer tick, asking Buildbucket for the build status
[19:16:44.157] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:44.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:42:0) after 1m0s
[19:17:44.177] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:42:0)
[19:17:44.177] Timer tick, asking Buildbucket for the build status
[19:17:44.211] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:44.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:43:0) after 1m0s
[19:18:44.235] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:43:0)
[19:18:44.235] Timer tick, asking Buildbucket for the build status
[19:18:44.313] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:44.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:44:0) after 1m0s
[19:19:44.335] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:44:0)
[19:19:44.335] Timer tick, asking Buildbucket for the build status
[19:19:44.550] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:44.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:45:0) after 1m0s
[19:20:44.580] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:45:0)
[19:20:44.580] Timer tick, asking Buildbucket for the build status
[19:20:44.712] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:44.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:46:0) after 1m0s
[19:21:44.752] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:46:0)
[19:21:44.752] Timer tick, asking Buildbucket for the build status
[19:21:44.876] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:44.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:47:0) after 1m0s
[19:22:44.910] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:47:0)
[19:22:44.910] Timer tick, asking Buildbucket for the build status
[19:22:44.963] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:44.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:48:0) after 1m0s
[19:23:45.002] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:48:0)
[19:23:45.002] Timer tick, asking Buildbucket for the build status
[19:23:45.052] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:45.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:49:0) after 1m0s
[19:24:45.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:49:0)
[19:24:45.114] Timer tick, asking Buildbucket for the build status
[19:24:45.165] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:45.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:50:0) after 1m0s
[19:25:45.286] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:50:0)
[19:25:45.286] Timer tick, asking Buildbucket for the build status
[19:25:46.235] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:46.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:51:0) after 1m0s
[19:26:46.257] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:51:0)
[19:26:46.257] Timer tick, asking Buildbucket for the build status
[19:26:46.320] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:46.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:52:0) after 1m0s
[19:27:46.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:52:0)
[19:27:46.352] Timer tick, asking Buildbucket for the build status
[19:27:46.432] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:46.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:53:0) after 1m0s
[19:28:46.459] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:53:0)
[19:28:46.459] Timer tick, asking Buildbucket for the build status
[19:28:46.490] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:46.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:54:0) after 1m0s
[19:29:46.525] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:54:0)
[19:29:46.525] Timer tick, asking Buildbucket for the build status
[19:29:46.572] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:46.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:55:0) after 1m0s
[19:30:46.590] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:55:0)
[19:30:46.590] Timer tick, asking Buildbucket for the build status
[19:30:46.701] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:46.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:56:0) after 1m0s
[19:31:46.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:56:0)
[19:31:46.722] Timer tick, asking Buildbucket for the build status
[19:31:46.866] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:46.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:57:0) after 1m0s
[19:32:46.884] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:57:0)
[19:32:46.884] Timer tick, asking Buildbucket for the build status
[19:32:46.930] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:46.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:58:0) after 1m0s
[19:33:46.954] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:58:0)
[19:33:46.954] Timer tick, asking Buildbucket for the build status
[19:33:47.016] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:47.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:59:0) after 1m0s
[19:34:47.043] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:59:0)
[19:34:47.043] Timer tick, asking Buildbucket for the build status
[19:34:47.127] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:47.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:60:0) after 1m0s
[19:35:47.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:60:0)
[19:35:47.153] Timer tick, asking Buildbucket for the build status
[19:35:47.214] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:47.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:61:0) after 1m0s
[19:36:47.239] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:61:0)
[19:36:47.239] Timer tick, asking Buildbucket for the build status
[19:36:47.288] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:47.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:62:0) after 1m0s
[19:37:47.311] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:62:0)
[19:37:47.311] Timer tick, asking Buildbucket for the build status
[19:37:47.469] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:47.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:63:0) after 1m0s
[19:38:47.489] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:63:0)
[19:38:47.489] Timer tick, asking Buildbucket for the build status
[19:38:47.549] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:47.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:64:0) after 1m0s
[19:39:47.658] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:64:0)
[19:39:47.658] Timer tick, asking Buildbucket for the build status
[19:39:47.767] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:47.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:65:0) after 1m0s
[19:40:47.795] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:65:0)
[19:40:47.795] Timer tick, asking Buildbucket for the build status
[19:40:47.842] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:47.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:66:0) after 1m0s
[19:41:47.939] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:66:0)
[19:41:47.939] Timer tick, asking Buildbucket for the build status
[19:41:48.022] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:48.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:67:0) after 1m0s
[19:42:48.059] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:67:0)
[19:42:48.059] Timer tick, asking Buildbucket for the build status
[19:42:48.098] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:48.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:68:0) after 1m0s
[19:43:48.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:68:0)
[19:43:48.117] Timer tick, asking Buildbucket for the build status
[19:43:48.190] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:48.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:69:0) after 1m0s
[19:44:48.251] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:69:0)
[19:44:48.251] Timer tick, asking Buildbucket for the build status
[19:44:48.300] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:48.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:70:0) after 1m0s
[19:45:48.351] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:70:0)
[19:45:48.351] Timer tick, asking Buildbucket for the build status
[19:45:48.518] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:48.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:71:0) after 1m0s
[19:46:48.544] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:71:0)
[19:46:48.544] Timer tick, asking Buildbucket for the build status
[19:46:48.697] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:48.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:72:0) after 1m0s
[19:47:48.731] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:72:0)
[19:47:48.731] Timer tick, asking Buildbucket for the build status
[19:47:48.818] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:48.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:73:0) after 1m0s
[19:48:48.873] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:73:0)
[19:48:48.873] Timer tick, asking Buildbucket for the build status
[19:48:48.923] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:48.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:74:0) after 1m0s
[19:49:49.003] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:74:0)
[19:49:49.003] Timer tick, asking Buildbucket for the build status
[19:49:49.154] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:49.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:75:0) after 1m0s
[19:50:49.177] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:75:0)
[19:50:49.177] Timer tick, asking Buildbucket for the build status
[19:50:49.258] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:49.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:76:0) after 1m0s
[19:51:49.277] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:76:0)
[19:51:49.277] Timer tick, asking Buildbucket for the build status
[19:51:49.370] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:49.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:77:0) after 1m0s
[19:52:49.418] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:77:0)
[19:52:49.418] Timer tick, asking Buildbucket for the build status
[19:52:49.504] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:49.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:78:0) after 1m0s
[19:53:49.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:78:0)
[19:53:49.567] Timer tick, asking Buildbucket for the build status
[19:53:50.553] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:50.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:79:0) after 1m0s
[19:54:50.578] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:79:0)
[19:54:50.578] Timer tick, asking Buildbucket for the build status
[19:54:50.674] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:50.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:80:0) after 1m0s
[19:55:50.719] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:80:0)
[19:55:50.719] Timer tick, asking Buildbucket for the build status
[19:55:50.766] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:50.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:81:0) after 1m0s
[19:56:50.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:81:0)
[19:56:50.786] Timer tick, asking Buildbucket for the build status
[19:56:50.934] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:50.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:82:0) after 1m0s
[19:57:50.963] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:82:0)
[19:57:50.963] Timer tick, asking Buildbucket for the build status
[19:57:51.024] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:51.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:83:0) after 1m0s
[19:58:51.072] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:83:0)
[19:58:51.072] Timer tick, asking Buildbucket for the build status
[19:58:51.207] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:51.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:84:0) after 1m0s
[19:59:51.240] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:84:0)
[19:59:51.240] Timer tick, asking Buildbucket for the build status
[19:59:51.791] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:51.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:85:0) after 1m0s
[20:00:51.812] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:85:0)
[20:00:51.812] Timer tick, asking Buildbucket for the build status
[20:00:51.949] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:51.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:86:0) after 1m0s
[20:01:51.970] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:86:0)
[20:01:51.970] Timer tick, asking Buildbucket for the build status
[20:01:52.089] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:52.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:87:0) after 1m0s
[20:02:52.108] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:87:0)
[20:02:52.108] Timer tick, asking Buildbucket for the build status
[20:02:52.230] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:52.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:88:0) after 1m0s
[20:03:52.254] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:88:0)
[20:03:52.254] Timer tick, asking Buildbucket for the build status
[20:03:52.526] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:52.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:89:0) after 1m0s
[20:04:52.546] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:89:0)
[20:04:52.546] Timer tick, asking Buildbucket for the build status
[20:04:52.642] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:52.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:90:0) after 1m0s
[20:05:52.666] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:90:0)
[20:05:52.666] Timer tick, asking Buildbucket for the build status
[20:05:52.723] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:52.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:91:0) after 1m0s
[20:06:52.754] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:91:0)
[20:06:52.754] Timer tick, asking Buildbucket for the build status
[20:06:52.826] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:52.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:92:0) after 1m0s
[20:07:52.867] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:92:0)
[20:07:52.867] Timer tick, asking Buildbucket for the build status
[20:07:52.971] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:52.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:93:0) after 1m0s
[20:08:52.998] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:93:0)
[20:08:52.998] Timer tick, asking Buildbucket for the build status
[20:08:53.098] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:53.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:94:0) after 1m0s
[20:09:53.118] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:94:0)
[20:09:53.118] Timer tick, asking Buildbucket for the build status
[20:09:53.209] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:53.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:95:0) after 1m0s
[20:10:53.231] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:95:0)
[20:10:53.231] Timer tick, asking Buildbucket for the build status
[20:10:53.458] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:53.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:96:0) after 1m0s
[20:11:53.491] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:96:0)
[20:11:53.491] Timer tick, asking Buildbucket for the build status
[20:11:53.551] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:53.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:97:0) after 1m0s
[20:12:53.574] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:97:0)
[20:12:53.574] Timer tick, asking Buildbucket for the build status
[20:12:53.717] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:53.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:98:0) after 1m0s
[20:13:53.740] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:98:0)
[20:13:53.740] Timer tick, asking Buildbucket for the build status
[20:13:54.031] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:54.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:99:0) after 1m0s
[20:14:54.069] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:99:0)
[20:14:54.069] Timer tick, asking Buildbucket for the build status
[20:14:54.266] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:54.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:100:0) after 1m0s
[20:15:54.362] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:100:0)
[20:15:54.362] Timer tick, asking Buildbucket for the build status
[20:15:54.409] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:54.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:101:0) after 1m0s
[20:16:54.435] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:101:0)
[20:16:54.435] Timer tick, asking Buildbucket for the build status
[20:16:54.481] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:54.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:102:0) after 1m0s
[20:17:54.554] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:102:0)
[20:17:54.554] Timer tick, asking Buildbucket for the build status
[20:17:54.624] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:54.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:103:0) after 1m0s
[20:18:54.645] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:103:0)
[20:18:54.645] Timer tick, asking Buildbucket for the build status
[20:18:54.701] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:54.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:104:0) after 1m0s
[20:19:54.822] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:104:0)
[20:19:54.822] Timer tick, asking Buildbucket for the build status
[20:19:55.004] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:55.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:105:0) after 1m0s
[20:20:55.028] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:105:0)
[20:20:55.028] Timer tick, asking Buildbucket for the build status
[20:20:55.085] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:55.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:106:0) after 1m0s
[20:21:55.109] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:106:0)
[20:21:55.109] Timer tick, asking Buildbucket for the build status
[20:21:55.176] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:55.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:107:0) after 1m0s
[20:22:55.195] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:107:0)
[20:22:55.195] Timer tick, asking Buildbucket for the build status
[20:22:55.396] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:55.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:108:0) after 1m0s
[20:23:55.415] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:108:0)
[20:23:55.415] Timer tick, asking Buildbucket for the build status
[20:23:55.568] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:55.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:109:0) after 1m0s
[20:24:55.594] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:109:0)
[20:24:55.594] Timer tick, asking Buildbucket for the build status
[20:24:55.702] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:55.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:110:0) after 1m0s
[20:25:55.726] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:110:0)
[20:25:55.726] Timer tick, asking Buildbucket for the build status
[20:25:55.814] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:55.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:111:0) after 1m0s
[20:26:55.836] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:111:0)
[20:26:55.836] Timer tick, asking Buildbucket for the build status
[20:26:55.910] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:55.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:112:0) after 1m0s
[20:27:55.943] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:112:0)
[20:27:55.943] Timer tick, asking Buildbucket for the build status
[20:27:55.990] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:55.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:113:0) after 1m0s
[20:28:56.012] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:113:0)
[20:28:56.012] Timer tick, asking Buildbucket for the build status
[20:28:56.166] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:56.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:114:0) after 1m0s
[20:29:56.189] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:114:0)
[20:29:56.189] Timer tick, asking Buildbucket for the build status
[20:29:56.239] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:56.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:115:0) after 1m0s
[20:30:56.259] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:115:0)
[20:30:56.259] Timer tick, asking Buildbucket for the build status
[20:30:56.378] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:56.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:116:0) after 1m0s
[20:31:56.413] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:116:0)
[20:31:56.413] Timer tick, asking Buildbucket for the build status
[20:31:56.471] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:56.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:117:0) after 1m0s
[20:32:56.544] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:117:0)
[20:32:56.544] Timer tick, asking Buildbucket for the build status
[20:32:56.614] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:56.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:118:0) after 1m0s
[20:33:56.634] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:118:0)
[20:33:56.634] Timer tick, asking Buildbucket for the build status
[20:33:56.681] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:56.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:119:0) after 1m0s
[20:34:56.708] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:119:0)
[20:34:56.708] Timer tick, asking Buildbucket for the build status
[20:34:57.413] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:57.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:120:0) after 1m0s
[20:35:57.434] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:120:0)
[20:35:57.434] Timer tick, asking Buildbucket for the build status
[20:35:57.509] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:57.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:121:0) after 1m0s
[20:36:57.538] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:121:0)
[20:36:57.538] Timer tick, asking Buildbucket for the build status
[20:36:57.627] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:57.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:122:0) after 1m0s
[20:37:57.650] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:122:0)
[20:37:57.650] Timer tick, asking Buildbucket for the build status
[20:37:57.735] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:123:0) after 1m0s
[20:38:57.839] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:123:0)
[20:38:57.839] Timer tick, asking Buildbucket for the build status
[20:38:58.043] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:58.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:124:0) after 1m0s
[20:39:58.070] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:124:0)
[20:39:58.070] Timer tick, asking Buildbucket for the build status
[20:39:58.120] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:58.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:125:0) after 1m0s
[20:40:58.144] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:125:0)
[20:40:58.144] Timer tick, asking Buildbucket for the build status
[20:40:58.404] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:126:0) after 1m0s
[20:41:58.432] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:126:0)
[20:41:58.432] Timer tick, asking Buildbucket for the build status
[20:41:58.609] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:58.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:127:0) after 1m0s
[20:42:58.647] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:127:0)
[20:42:58.647] Timer tick, asking Buildbucket for the build status
[20:42:58.751] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:58.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:128:0) after 1m0s
[20:43:58.780] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:128:0)
[20:43:58.780] Timer tick, asking Buildbucket for the build status
[20:43:58.845] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:58.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:129:0) after 1m0s
[20:44:58.867] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:129:0)
[20:44:58.867] Timer tick, asking Buildbucket for the build status
[20:44:59.056] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:130:0) after 1m0s
[20:45:59.178] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:130:0)
[20:45:59.178] Timer tick, asking Buildbucket for the build status
[20:45:59.228] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:59.228] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:131:0) after 1m0s
[20:46:59.264] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:131:0)
[20:46:59.264] Timer tick, asking Buildbucket for the build status
[20:46:59.376] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:59.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:132:0) after 1m0s
[20:47:59.396] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:132:0)
[20:47:59.396] Timer tick, asking Buildbucket for the build status
[20:47:59.457] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:59.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:133:0) after 1m0s
[20:48:59.570] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:133:0)
[20:48:59.570] Timer tick, asking Buildbucket for the build status
[20:48:59.644] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:59.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:134:0) after 1m0s
[20:49:59.663] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:134:0)
[20:49:59.663] Timer tick, asking Buildbucket for the build status
[20:49:59.878] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:59.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:135:0) after 1m0s
[20:50:59.902] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:135:0)
[20:50:59.902] Timer tick, asking Buildbucket for the build status
[20:51:00.024] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:00.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:136:0) after 1m0s
[20:52:00.051] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:136:0)
[20:52:00.051] Timer tick, asking Buildbucket for the build status
[20:52:00.151] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:00.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:137:0) after 1m0s
[20:53:00.175] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:137:0)
[20:53:00.175] Timer tick, asking Buildbucket for the build status
[20:53:00.371] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:00.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:138:0) after 1m0s
[20:54:00.396] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:138:0)
[20:54:00.396] Timer tick, asking Buildbucket for the build status
[20:54:00.484] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:00.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:139:0) after 1m0s
[20:55:00.516] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:139:0)
[20:55:00.516] Timer tick, asking Buildbucket for the build status
[20:55:00.632] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:00.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:140:0) after 1m0s
[20:56:00.655] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:140:0)
[20:56:00.655] Timer tick, asking Buildbucket for the build status
[20:56:00.716] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:00.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:141:0) after 1m0s
[20:57:00.737] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:141:0)
[20:57:00.737] Timer tick, asking Buildbucket for the build status
[20:57:00.880] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:00.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:142:0) after 1m0s
[20:58:00.908] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:142:0)
[20:58:00.908] Timer tick, asking Buildbucket for the build status
[20:58:01.000] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:01.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:143:0) after 1m0s
[20:59:01.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:143:0)
[20:59:01.029] Timer tick, asking Buildbucket for the build status
[20:59:01.141] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:01.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:144:0) after 1m0s
[21:00:01.164] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:144:0)
[21:00:01.164] Timer tick, asking Buildbucket for the build status
[21:00:01.349] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:01.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:145:0) after 1m0s
[21:01:01.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:145:0)
[21:01:01.384] Timer tick, asking Buildbucket for the build status
[21:01:01.445] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:01.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:146:0) after 1m0s
[21:02:01.471] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:146:0)
[21:02:01.471] Timer tick, asking Buildbucket for the build status
[21:02:01.658] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:01.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:147:0) after 1m0s
[21:03:01.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:147:0)
[21:03:01.679] Timer tick, asking Buildbucket for the build status
[21:03:01.842] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:01.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:148:0) after 1m0s
[21:04:01.865] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:148:0)
[21:04:01.865] Timer tick, asking Buildbucket for the build status
[21:04:01.921] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:01.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:149:0) after 1m0s
[21:05:01.954] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:149:0)
[21:05:01.954] Timer tick, asking Buildbucket for the build status
[21:05:02.184] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:02.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:150:0) after 1m0s
[21:06:02.211] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:150:0)
[21:06:02.211] Timer tick, asking Buildbucket for the build status
[21:06:02.269] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:02.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:151:0) after 1m0s
[21:07:02.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:151:0)
[21:07:02.299] Timer tick, asking Buildbucket for the build status
[21:07:02.429] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:02.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:152:0) after 1m0s
[21:08:02.450] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:152:0)
[21:08:02.450] Timer tick, asking Buildbucket for the build status
[21:08:02.525] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:02.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:153:0) after 1m0s
[21:09:02.552] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:153:0)
[21:09:02.552] Timer tick, asking Buildbucket for the build status
[21:09:02.601] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:02.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:154:0) after 1m0s
[21:10:03.012] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:154:0)
[21:10:03.012] Timer tick, asking Buildbucket for the build status
[21:10:03.350] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:03.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:155:0) after 1m0s
[21:11:03.578] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:155:0)
[21:11:03.578] Timer tick, asking Buildbucket for the build status
[21:11:03.630] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:03.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:156:0) after 1m0s
[21:12:03.706] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:156:0)
[21:12:03.706] Timer tick, asking Buildbucket for the build status
[21:12:03.832] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:03.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:157:0) after 1m0s
[21:13:03.853] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:157:0)
[21:13:03.853] Timer tick, asking Buildbucket for the build status
[21:13:04.011] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:04.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:158:0) after 1m0s
[21:14:04.188] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:158:0)
[21:14:04.188] Timer tick, asking Buildbucket for the build status
[21:14:04.276] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:04.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:159:0) after 1m0s
[21:15:04.307] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:159:0)
[21:15:04.307] Timer tick, asking Buildbucket for the build status
[21:15:04.438] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:04.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:160:0) after 1m0s
[21:16:04.465] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:160:0)
[21:16:04.465] Timer tick, asking Buildbucket for the build status
[21:16:04.510] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:04.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:161:0) after 1m0s
[21:17:04.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:161:0)
[21:17:04.567] Timer tick, asking Buildbucket for the build status
[21:17:04.646] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:04.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:162:0) after 1m0s
[21:18:04.731] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:162:0)
[21:18:04.731] Timer tick, asking Buildbucket for the build status
[21:18:04.935] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:04.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:163:0) after 1m0s
[21:19:04.960] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:163:0)
[21:19:04.960] Timer tick, asking Buildbucket for the build status
[21:19:05.031] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:05.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:164:0) after 1m0s
[21:20:05.060] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:164:0)
[21:20:05.060] Timer tick, asking Buildbucket for the build status
[21:20:05.138] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:05.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:165:0) after 1m0s
[21:21:05.164] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:165:0)
[21:21:05.164] Timer tick, asking Buildbucket for the build status
[21:21:05.244] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:05.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:166:0) after 1m0s
[21:22:05.269] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:166:0)
[21:22:05.269] Timer tick, asking Buildbucket for the build status
[21:22:05.512] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:05.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:167:0) after 1m0s
[21:23:05.540] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:167:0)
[21:23:05.540] Timer tick, asking Buildbucket for the build status
[21:23:05.601] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:05.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:168:0) after 1m0s
[21:24:05.684] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:168:0)
[21:24:05.684] Timer tick, asking Buildbucket for the build status
[21:24:05.838] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:05.838] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:169:0) after 1m0s
[21:25:05.869] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:169:0)
[21:25:05.869] Timer tick, asking Buildbucket for the build status
[21:25:05.924] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:05.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:170:0) after 1m0s
[21:26:05.956] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:170:0)
[21:26:05.956] Timer tick, asking Buildbucket for the build status
[21:26:06.024] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:06.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:171:0) after 1m0s
[21:27:06.046] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:171:0)
[21:27:06.046] Timer tick, asking Buildbucket for the build status
[21:27:06.114] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:06.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:172:0) after 1m0s
[21:28:06.174] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:172:0)
[21:28:06.174] Timer tick, asking Buildbucket for the build status
[21:28:06.423] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:06.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:173:0) after 1m0s
[21:29:06.455] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:173:0)
[21:29:06.455] Timer tick, asking Buildbucket for the build status
[21:29:06.611] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:06.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:174:0) after 1m0s
[21:30:06.645] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:174:0)
[21:30:06.645] Timer tick, asking Buildbucket for the build status
[21:30:06.717] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:06.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:175:0) after 1m0s
[21:31:06.746] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:175:0)
[21:31:06.746] Timer tick, asking Buildbucket for the build status
[21:31:06.881] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:06.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:176:0) after 1m0s
[21:32:06.905] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:176:0)
[21:32:06.905] Timer tick, asking Buildbucket for the build status
[21:32:06.949] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:06.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:177:0) after 1m0s
[21:33:06.993] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:177:0)
[21:33:06.993] Timer tick, asking Buildbucket for the build status
[21:33:07.122] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:07.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:178:0) after 1m0s
[21:34:07.149] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:178:0)
[21:34:07.149] Timer tick, asking Buildbucket for the build status
[21:34:07.638] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:07.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:179:0) after 1m0s
[21:35:07.676] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:179:0)
[21:35:07.676] Timer tick, asking Buildbucket for the build status
[21:35:07.767] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:07.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:180:0) after 1m0s
[21:36:07.793] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:180:0)
[21:36:07.793] Timer tick, asking Buildbucket for the build status
[21:36:07.844] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:07.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:181:0) after 1m0s
[21:37:07.871] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:181:0)
[21:37:07.871] Timer tick, asking Buildbucket for the build status
[21:37:07.921] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:07.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:182:0) after 1m0s
[21:38:07.942] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:182:0)
[21:38:07.942] Timer tick, asking Buildbucket for the build status
[21:38:09.022] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:09.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:183:0) after 1m0s
[21:39:09.054] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:183:0)
[21:39:09.054] Timer tick, asking Buildbucket for the build status
[21:39:09.203] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:184:0) after 1m0s
[21:40:09.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:184:0)
[21:40:09.242] Timer tick, asking Buildbucket for the build status
[21:40:09.336] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:09.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:185:0) after 1m0s
[21:41:09.366] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:185:0)
[21:41:09.366] Timer tick, asking Buildbucket for the build status
[21:41:09.490] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:09.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:186:0) after 1m0s
[21:42:09.512] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:186:0)
[21:42:09.512] Timer tick, asking Buildbucket for the build status
[21:42:09.707] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:09.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:187:0) after 1m0s
[21:43:09.735] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:187:0)
[21:43:09.735] Timer tick, asking Buildbucket for the build status
[21:43:09.852] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:09.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:188:0) after 1m0s
[21:44:09.875] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:188:0)
[21:44:09.875] Timer tick, asking Buildbucket for the build status
[21:44:09.919] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:09.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:189:0) after 1m0s
[21:45:09.961] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:189:0)
[21:45:09.961] Timer tick, asking Buildbucket for the build status
[21:45:10.053] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:10.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:190:0) after 1m0s
[21:46:10.075] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:190:0)
[21:46:10.075] Timer tick, asking Buildbucket for the build status
[21:46:10.172] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:10.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:191:0) after 1m0s
[21:47:10.202] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:191:0)
[21:47:10.202] Timer tick, asking Buildbucket for the build status
[21:47:10.321] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:10.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:192:0) after 1m0s
[21:48:10.361] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:192:0)
[21:48:10.361] Timer tick, asking Buildbucket for the build status
[21:48:10.418] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:10.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:193:0) after 1m0s
[21:49:10.426] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:193:0)
[21:49:10.426] Timer tick, asking Buildbucket for the build status
[21:49:10.707] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:10.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:194:0) after 1m0s
[21:50:10.732] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:194:0)
[21:50:10.732] Timer tick, asking Buildbucket for the build status
[21:50:10.804] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:10.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:195:0) after 1m0s
[21:51:10.830] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:195:0)
[21:51:10.830] Timer tick, asking Buildbucket for the build status
[21:51:11.059] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:11.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:196:0) after 1m0s
[21:52:11.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:196:0)
[21:52:11.113] Timer tick, asking Buildbucket for the build status
[21:52:11.195] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:11.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:197:0) after 1m0s
[21:53:11.217] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:197:0)
[21:53:11.217] Timer tick, asking Buildbucket for the build status
[21:53:11.258] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:11.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:198:0) after 1m0s
[21:54:11.294] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:198:0)
[21:54:11.294] Timer tick, asking Buildbucket for the build status
[21:54:11.343] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:11.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:199:0) after 1m0s
[21:55:11.382] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:199:0)
[21:55:11.382] Timer tick, asking Buildbucket for the build status
[21:55:11.537] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:11.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:200:0) after 1m0s
[21:56:11.559] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:200:0)
[21:56:11.559] Timer tick, asking Buildbucket for the build status
[21:56:11.651] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:11.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:201:0) after 1m0s
[21:57:11.674] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:201:0)
[21:57:11.674] Timer tick, asking Buildbucket for the build status
[21:57:11.778] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:11.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:202:0) after 1m0s
[21:58:11.809] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:202:0)
[21:58:11.809] Timer tick, asking Buildbucket for the build status
[21:58:11.872] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:11.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:203:0) after 1m0s
[21:59:11.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:203:0)
[21:59:11.854] Timer tick, asking Buildbucket for the build status
[21:59:11.911] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:11.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:204:0) after 1m0s
[22:00:11.934] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:204:0)
[22:00:11.934] Timer tick, asking Buildbucket for the build status
[22:00:12.202] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:205:0) after 1m0s
[22:01:12.234] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:205:0)
[22:01:12.234] Timer tick, asking Buildbucket for the build status
[22:01:12.416] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:12.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:206:0) after 1m0s
[22:02:12.447] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:206:0)
[22:02:12.447] Timer tick, asking Buildbucket for the build status
[22:02:12.835] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:12.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:207:0) after 1m0s
[22:03:12.870] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:207:0)
[22:03:12.870] Timer tick, asking Buildbucket for the build status
[22:03:12.911] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:12.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:208:0) after 1m0s
[22:04:12.943] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:208:0)
[22:04:12.943] Timer tick, asking Buildbucket for the build status
[22:04:13.010] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:13.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:209:0) after 1m0s
[22:05:13.036] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:209:0)
[22:05:13.036] Timer tick, asking Buildbucket for the build status
[22:05:13.163] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:13.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:210:0) after 1m0s
[22:06:13.229] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:210:0)
[22:06:13.229] Timer tick, asking Buildbucket for the build status
[22:06:13.455] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:13.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:211:0) after 1m0s
[22:07:13.484] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:211:0)
[22:07:13.484] Timer tick, asking Buildbucket for the build status
[22:07:13.606] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:13.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:212:0) after 1m0s
[22:08:13.627] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:212:0)
[22:08:13.627] Timer tick, asking Buildbucket for the build status
[22:08:13.792] Build 8925237703612064064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:13.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9090667765973908080:213:0) after 1m0s
[22:08:32.047] Received PubSub notification, asking Buildbucket for the build status
[22:08:32.092] Build 8925237703612064064: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:08:32.092] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1546639711234108",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1546627056452165",
    "failure_reason": "BUILD_FAILURE",
    "id": "8925237703612064064",
    "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\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure predownload validator corpus\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3945-402d3be\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-c-ttjg\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"git\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 91, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1546627056452165, \"tags\": [\"builder:linux-64-validator-opt\", \"buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9090667765973908080\", \"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\": \"8925237703612064064\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-c-ttjg\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1546627101681229",
    "status": "COMPLETED",
    "status_changed_ts": "1546639711706855",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/91",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9090667765973908080",
      "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/91",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8925237703612064064",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "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/8925237703612064064/+/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:9090667765973908080",
      "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:8df9132fd2891579a00ae465513e90e9036bee95",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4232f98f3037a310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1546639711707906",
    "url": "https://ci.chromium.org/b/8925237703612064064",
    "utcnow_ts": "1546639712080901"
  }
}
[22:08:32.092] Invocation finished in 3h30m57.276333254s with status FAILED