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

• 787866ca5f2a69470998b1e07cfc1426e2a41260 (6 years ago)

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

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

[23:11:33.033] New invocation is queued and will start shortly
[23:11:34.603] Starting the invocation (attempt 1)
[23:11:34.634] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:11:34.634] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:11:34.634] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9089563365676301872",
  "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\":\"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
  "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:9089563365676301872",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
    "gitiles_ref:refs/heads/master"
  ]
}
[23:11:35.815] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547680294706534",
    "id": "8924133303257135328",
    "lease_expiration_ts": "1547831495569264",
    "lease_key": "387751742",
    "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\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1547680295717572",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/93",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089563365676301872",
      "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/93",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8924133303257135328",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "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/8924133303257135328/+/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:9089563365676301872",
      "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:e1d5da8663c0550abd5affb209a08bfdb819ce3f",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4271c0b1a9302b10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547680295717647",
    "url": "https://ci.chromium.org/b/8924133303257135328",
    "utcnow_ts": "1547680295800360"
  }
}
[23:11:35.815] Task URL: https://ci.chromium.org/b/8924133303257135328
[23:11:35.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:2:0) after 1m0s
[23:12:36.044] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:2:0)
[23:12:36.044] Timer tick, asking Buildbucket for the build status
[23:12:36.248] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:36.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:3:0) after 1m0s
[23:13:36.291] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:3:0)
[23:13:36.291] Timer tick, asking Buildbucket for the build status
[23:13:36.378] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:36.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:4:0) after 1m0s
[23:14:36.413] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:4:0)
[23:14:36.413] Timer tick, asking Buildbucket for the build status
[23:14:36.474] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:36.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:5:0) after 1m0s
[23:15:36.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:5:0)
[23:15:36.493] Timer tick, asking Buildbucket for the build status
[23:15:36.543] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:36.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:6:0) after 1m0s
[23:16:36.560] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:6:0)
[23:16:36.560] Timer tick, asking Buildbucket for the build status
[23:16:36.703] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:36.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:7:0) after 1m0s
[23:17:36.721] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:7:0)
[23:17:36.721] Timer tick, asking Buildbucket for the build status
[23:17:36.775] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:36.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:8:0) after 1m0s
[23:18:36.796] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:8:0)
[23:18:36.796] Timer tick, asking Buildbucket for the build status
[23:18:36.855] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:36.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:9:0) after 1m0s
[23:19:36.872] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:9:0)
[23:19:36.872] Timer tick, asking Buildbucket for the build status
[23:19:36.907] Build 8924133303257135328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:36.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:10:0) after 1m0s
[23:19:58.742] Received PubSub notification, asking Buildbucket for the build status
[23:19:58.774] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.925] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:10:0)
[23:20:36.925] Timer tick, asking Buildbucket for the build status
[23:20:36.988] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:12:0) after 1m0s
[23:21:37.032] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:12:0)
[23:21:37.032] Timer tick, asking Buildbucket for the build status
[23:21:37.143] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:13:0) after 1m0s
[23:22:37.159] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:13:0)
[23:22:37.159] Timer tick, asking Buildbucket for the build status
[23:22:37.384] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:37.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:14:0) after 1m0s
[23:23:37.402] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:14:0)
[23:23:37.402] Timer tick, asking Buildbucket for the build status
[23:23:37.462] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:37.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:15:0) after 1m0s
[23:24:37.478] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:15:0)
[23:24:37.478] Timer tick, asking Buildbucket for the build status
[23:24:37.577] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:37.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:16:0) after 1m0s
[23:25:37.594] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:16:0)
[23:25:37.594] Timer tick, asking Buildbucket for the build status
[23:25:37.656] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:37.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:17:0) after 1m0s
[23:26:37.676] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:17:0)
[23:26:37.676] Timer tick, asking Buildbucket for the build status
[23:26:37.738] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:37.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:18:0) after 1m0s
[23:27:37.753] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:18:0)
[23:27:37.753] Timer tick, asking Buildbucket for the build status
[23:27:37.813] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:37.813] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:19:0) after 1m0s
[23:28:37.830] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:19:0)
[23:28:37.830] Timer tick, asking Buildbucket for the build status
[23:28:37.863] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:37.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:20:0) after 1m0s
[23:29:37.882] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:20:0)
[23:29:37.882] Timer tick, asking Buildbucket for the build status
[23:29:37.929] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:37.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:21:0) after 1m0s
[23:30:37.946] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:21:0)
[23:30:37.946] Timer tick, asking Buildbucket for the build status
[23:30:37.984] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:22:0) after 1m0s
[23:31:38.001] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:22:0)
[23:31:38.001] Timer tick, asking Buildbucket for the build status
[23:31:38.052] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:23:0) after 1m0s
[23:32:38.073] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:23:0)
[23:32:38.073] Timer tick, asking Buildbucket for the build status
[23:32:38.163] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:24:0) after 1m0s
[23:33:38.259] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:24:0)
[23:33:38.259] Timer tick, asking Buildbucket for the build status
[23:33:38.296] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:38.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:25:0) after 1m0s
[23:34:38.316] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:25:0)
[23:34:38.316] Timer tick, asking Buildbucket for the build status
[23:34:38.379] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:38.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:26:0) after 1m0s
[23:35:38.400] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:26:0)
[23:35:38.400] Timer tick, asking Buildbucket for the build status
[23:35:38.498] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:38.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:27:0) after 1m0s
[23:36:40.458] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:27:0)
[23:36:40.458] Timer tick, asking Buildbucket for the build status
[23:36:40.515] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:40.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:28:0) after 1m0s
[23:37:40.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:28:0)
[23:37:40.567] Timer tick, asking Buildbucket for the build status
[23:37:40.602] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:40.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:29:0) after 1m0s
[23:38:40.620] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:29:0)
[23:38:40.620] Timer tick, asking Buildbucket for the build status
[23:38:40.759] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:40.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:30:0) after 1m0s
[23:39:40.790] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:30:0)
[23:39:40.790] Timer tick, asking Buildbucket for the build status
[23:39:40.821] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:40.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:31:0) after 1m0s
[23:40:40.855] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:31:0)
[23:40:40.855] Timer tick, asking Buildbucket for the build status
[23:40:40.948] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:40.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:32:0) after 1m0s
[23:41:40.997] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:32:0)
[23:41:40.997] Timer tick, asking Buildbucket for the build status
[23:41:41.037] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:41.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:33:0) after 1m0s
[23:42:41.216] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:33:0)
[23:42:41.216] Timer tick, asking Buildbucket for the build status
[23:42:41.317] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:41.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:34:0) after 1m0s
[23:43:41.336] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:34:0)
[23:43:41.336] Timer tick, asking Buildbucket for the build status
[23:43:41.378] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:41.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:35:0) after 1m0s
[23:44:41.395] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:35:0)
[23:44:41.395] Timer tick, asking Buildbucket for the build status
[23:44:41.430] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:41.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:36:0) after 1m0s
[23:45:41.478] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:36:0)
[23:45:41.478] Timer tick, asking Buildbucket for the build status
[23:45:41.528] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:41.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:37:0) after 1m0s
[23:46:41.549] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:37:0)
[23:46:41.549] Timer tick, asking Buildbucket for the build status
[23:46:41.643] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:41.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:38:0) after 1m0s
[23:47:41.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:38:0)
[23:47:41.667] Timer tick, asking Buildbucket for the build status
[23:47:41.709] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:41.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:39:0) after 1m0s
[23:48:41.729] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:39:0)
[23:48:41.729] Timer tick, asking Buildbucket for the build status
[23:48:41.800] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:41.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:40:0) after 1m0s
[23:49:41.960] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:40:0)
[23:49:41.960] Timer tick, asking Buildbucket for the build status
[23:49:42.052] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:42.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:41:0) after 1m0s
[23:50:42.088] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:41:0)
[23:50:42.088] Timer tick, asking Buildbucket for the build status
[23:50:42.145] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:42.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:42:0) after 1m0s
[23:51:42.165] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:42:0)
[23:51:42.165] Timer tick, asking Buildbucket for the build status
[23:51:42.248] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:42.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:43:0) after 1m0s
[23:52:42.267] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:43:0)
[23:52:42.267] Timer tick, asking Buildbucket for the build status
[23:52:42.324] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:42.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:44:0) after 1m0s
[23:53:42.353] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:44:0)
[23:53:42.353] Timer tick, asking Buildbucket for the build status
[23:53:42.396] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:42.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:45:0) after 1m0s
[23:54:42.415] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:45:0)
[23:54:42.415] Timer tick, asking Buildbucket for the build status
[23:54:42.453] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:42.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:46:0) after 1m0s
[23:55:42.482] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:46:0)
[23:55:42.482] Timer tick, asking Buildbucket for the build status
[23:55:42.534] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:42.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:47:0) after 1m0s
[23:56:42.571] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:47:0)
[23:56:42.571] Timer tick, asking Buildbucket for the build status
[23:56:42.657] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:42.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:48:0) after 1m0s
[23:57:42.695] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:48:0)
[23:57:42.695] Timer tick, asking Buildbucket for the build status
[23:57:42.757] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:42.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:49:0) after 1m0s
[23:58:42.783] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:49:0)
[23:58:42.783] Timer tick, asking Buildbucket for the build status
[23:58:42.829] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:42.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:50:0) after 1m0s
[23:59:42.852] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:50:0)
[23:59:42.852] Timer tick, asking Buildbucket for the build status
[23:59:42.905] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:51:0) after 1m0s
[00:00:43.212] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:51:0)
[00:00:43.212] Timer tick, asking Buildbucket for the build status
[00:00:43.258] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:43.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:52:0) after 1m0s
[00:01:43.284] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:52:0)
[00:01:43.284] Timer tick, asking Buildbucket for the build status
[00:01:43.349] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:43.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:53:0) after 1m0s
[00:02:43.370] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:53:0)
[00:02:43.370] Timer tick, asking Buildbucket for the build status
[00:02:43.420] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:43.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:54:0) after 1m0s
[00:03:43.445] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:54:0)
[00:03:43.445] Timer tick, asking Buildbucket for the build status
[00:03:43.482] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:43.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:55:0) after 1m0s
[00:04:43.502] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:55:0)
[00:04:43.502] Timer tick, asking Buildbucket for the build status
[00:04:43.548] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:56:0) after 1m0s
[00:05:43.580] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:56:0)
[00:05:43.580] Timer tick, asking Buildbucket for the build status
[00:05:43.685] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:43.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:57:0) after 1m0s
[00:06:43.940] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:57:0)
[00:06:43.941] Timer tick, asking Buildbucket for the build status
[00:06:43.999] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:43.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:58:0) after 1m0s
[00:07:44.019] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:58:0)
[00:07:44.019] Timer tick, asking Buildbucket for the build status
[00:07:44.121] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:44.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:59:0) after 1m0s
[00:08:44.155] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:59:0)
[00:08:44.155] Timer tick, asking Buildbucket for the build status
[00:08:44.224] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:44.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:60:0) after 1m0s
[00:09:44.244] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:60:0)
[00:09:44.244] Timer tick, asking Buildbucket for the build status
[00:09:44.302] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:44.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:61:0) after 1m0s
[00:10:44.342] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:61:0)
[00:10:44.342] Timer tick, asking Buildbucket for the build status
[00:10:44.426] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:44.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:62:0) after 1m0s
[00:11:44.452] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:62:0)
[00:11:44.452] Timer tick, asking Buildbucket for the build status
[00:11:44.538] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:44.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:63:0) after 1m0s
[00:12:44.559] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:63:0)
[00:12:44.559] Timer tick, asking Buildbucket for the build status
[00:12:44.604] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:44.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:64:0) after 1m0s
[00:13:44.621] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:64:0)
[00:13:44.621] Timer tick, asking Buildbucket for the build status
[00:13:44.673] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:65:0) after 1m0s
[00:14:44.703] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:65:0)
[00:14:44.703] Timer tick, asking Buildbucket for the build status
[00:14:45.327] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:45.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:66:0) after 1m0s
[00:15:45.356] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:66:0)
[00:15:45.356] Timer tick, asking Buildbucket for the build status
[00:15:47.078] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:47.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:67:0) after 1m0s
[00:16:47.102] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:67:0)
[00:16:47.102] Timer tick, asking Buildbucket for the build status
[00:16:47.239] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:47.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:68:0) after 1m0s
[00:17:47.265] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:68:0)
[00:17:47.265] Timer tick, asking Buildbucket for the build status
[00:17:47.351] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:47.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:69:0) after 1m0s
[00:18:47.370] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:69:0)
[00:18:47.370] Timer tick, asking Buildbucket for the build status
[00:18:47.413] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:47.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:70:0) after 1m0s
[00:19:47.445] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:70:0)
[00:19:47.445] Timer tick, asking Buildbucket for the build status
[00:19:47.481] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:47.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:71:0) after 1m0s
[00:20:47.498] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:71:0)
[00:20:47.498] Timer tick, asking Buildbucket for the build status
[00:20:47.535] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:47.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:72:0) after 1m0s
[00:21:47.557] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:72:0)
[00:21:47.557] Timer tick, asking Buildbucket for the build status
[00:21:47.594] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:47.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:73:0) after 1m0s
[00:22:47.613] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:73:0)
[00:22:47.613] Timer tick, asking Buildbucket for the build status
[00:22:47.703] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:47.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:74:0) after 1m0s
[00:23:47.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:74:0)
[00:23:47.722] Timer tick, asking Buildbucket for the build status
[00:23:48.458] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:48.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:75:0) after 1m0s
[00:24:48.474] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:75:0)
[00:24:48.474] Timer tick, asking Buildbucket for the build status
[00:24:48.508] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:76:0) after 1m0s
[00:25:48.560] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:76:0)
[00:25:48.560] Timer tick, asking Buildbucket for the build status
[00:25:48.655] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:77:0) after 1m0s
[00:26:48.672] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:77:0)
[00:26:48.672] Timer tick, asking Buildbucket for the build status
[00:26:48.729] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:48.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:78:0) after 1m0s
[00:27:48.750] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:78:0)
[00:27:48.750] Timer tick, asking Buildbucket for the build status
[00:27:48.903] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:48.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:79:0) after 1m0s
[00:28:48.924] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:79:0)
[00:28:48.924] Timer tick, asking Buildbucket for the build status
[00:28:48.958] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:48.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:80:0) after 1m0s
[00:29:48.975] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:80:0)
[00:29:48.975] Timer tick, asking Buildbucket for the build status
[00:29:49.254] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:49.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:81:0) after 1m0s
[00:30:49.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:81:0)
[00:30:49.281] Timer tick, asking Buildbucket for the build status
[00:30:49.385] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:82:0) after 1m0s
[00:31:49.484] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:82:0)
[00:31:49.484] Timer tick, asking Buildbucket for the build status
[00:31:49.522] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:83:0) after 1m0s
[00:32:49.547] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:83:0)
[00:32:49.547] Timer tick, asking Buildbucket for the build status
[00:32:49.612] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:49.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:84:0) after 1m0s
[00:33:49.632] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:84:0)
[00:33:49.632] Timer tick, asking Buildbucket for the build status
[00:33:49.683] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:49.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:85:0) after 1m0s
[00:34:49.710] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:85:0)
[00:34:49.710] Timer tick, asking Buildbucket for the build status
[00:34:49.794] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:49.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:86:0) after 1m0s
[00:35:49.811] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:86:0)
[00:35:49.811] Timer tick, asking Buildbucket for the build status
[00:35:49.880] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:49.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:87:0) after 1m0s
[00:36:50.099] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:87:0)
[00:36:50.099] Timer tick, asking Buildbucket for the build status
[00:36:50.187] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:50.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:88:0) after 1m0s
[00:37:50.505] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:88:0)
[00:37:50.505] Timer tick, asking Buildbucket for the build status
[00:37:50.632] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:50.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:89:0) after 1m0s
[00:38:50.943] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:89:0)
[00:38:50.943] Timer tick, asking Buildbucket for the build status
[00:38:51.221] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:51.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:90:0) after 1m0s
[00:39:51.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:90:0)
[00:39:51.281] Timer tick, asking Buildbucket for the build status
[00:39:51.412] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:51.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:91:0) after 1m0s
[00:40:51.507] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:91:0)
[00:40:51.507] Timer tick, asking Buildbucket for the build status
[00:40:51.595] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:92:0) after 1m0s
[00:41:51.615] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:92:0)
[00:41:51.615] Timer tick, asking Buildbucket for the build status
[00:41:51.675] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:93:0) after 1m0s
[00:42:51.700] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:93:0)
[00:42:51.700] Timer tick, asking Buildbucket for the build status
[00:42:52.142] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:52.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:94:0) after 1m0s
[00:43:52.163] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:94:0)
[00:43:52.163] Timer tick, asking Buildbucket for the build status
[00:43:52.234] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:52.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:95:0) after 1m0s
[00:44:52.253] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:95:0)
[00:44:52.253] Timer tick, asking Buildbucket for the build status
[00:44:52.366] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:52.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:96:0) after 1m0s
[00:45:52.392] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:96:0)
[00:45:52.392] Timer tick, asking Buildbucket for the build status
[00:45:52.455] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:52.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:97:0) after 1m0s
[00:46:52.474] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:97:0)
[00:46:52.474] Timer tick, asking Buildbucket for the build status
[00:46:52.569] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:52.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:98:0) after 1m0s
[00:47:52.650] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:98:0)
[00:47:52.650] Timer tick, asking Buildbucket for the build status
[00:47:52.730] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:52.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:99:0) after 1m0s
[00:48:52.748] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:99:0)
[00:48:52.748] Timer tick, asking Buildbucket for the build status
[00:48:52.810] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:52.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:100:0) after 1m0s
[00:49:52.832] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:100:0)
[00:49:52.832] Timer tick, asking Buildbucket for the build status
[00:49:53.053] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:53.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:101:0) after 1m0s
[00:50:53.073] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:101:0)
[00:50:53.073] Timer tick, asking Buildbucket for the build status
[00:50:53.125] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:53.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:102:0) after 1m0s
[00:51:53.162] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:102:0)
[00:51:53.162] Timer tick, asking Buildbucket for the build status
[00:51:53.264] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:53.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:103:0) after 1m0s
[00:52:53.284] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:103:0)
[00:52:53.284] Timer tick, asking Buildbucket for the build status
[00:52:53.469] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:53.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:104:0) after 1m0s
[00:53:53.490] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:104:0)
[00:53:53.490] Timer tick, asking Buildbucket for the build status
[00:53:53.634] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:53.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:105:0) after 1m0s
[00:54:53.658] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:105:0)
[00:54:53.658] Timer tick, asking Buildbucket for the build status
[00:54:53.715] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:53.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:106:0) after 1m0s
[00:55:53.735] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:106:0)
[00:55:53.735] Timer tick, asking Buildbucket for the build status
[00:55:53.789] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:53.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:107:0) after 1m0s
[00:56:53.809] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:107:0)
[00:56:53.809] Timer tick, asking Buildbucket for the build status
[00:56:53.881] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:53.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:108:0) after 1m0s
[00:57:53.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:108:0)
[00:57:53.967] Timer tick, asking Buildbucket for the build status
[00:57:54.097] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:54.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:109:0) after 1m0s
[00:58:54.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:109:0)
[00:58:54.117] Timer tick, asking Buildbucket for the build status
[00:58:54.227] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:54.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:110:0) after 1m0s
[00:59:54.253] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:110:0)
[00:59:54.253] Timer tick, asking Buildbucket for the build status
[00:59:54.299] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:54.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:111:0) after 1m0s
[01:00:54.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:111:0)
[01:00:54.325] Timer tick, asking Buildbucket for the build status
[01:00:54.402] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:54.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:112:0) after 1m0s
[01:01:54.422] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:112:0)
[01:01:54.422] Timer tick, asking Buildbucket for the build status
[01:01:54.697] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:54.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:113:0) after 1m0s
[01:02:54.731] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:113:0)
[01:02:54.731] Timer tick, asking Buildbucket for the build status
[01:02:54.845] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:54.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:114:0) after 1m0s
[01:03:54.867] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:114:0)
[01:03:54.867] Timer tick, asking Buildbucket for the build status
[01:03:54.921] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:54.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:115:0) after 1m0s
[01:04:54.943] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:115:0)
[01:04:54.943] Timer tick, asking Buildbucket for the build status
[01:04:54.990] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:54.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:116:0) after 1m0s
[01:05:55.012] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:116:0)
[01:05:55.012] Timer tick, asking Buildbucket for the build status
[01:05:55.126] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:55.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:117:0) after 1m0s
[01:06:55.156] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:117:0)
[01:06:55.156] Timer tick, asking Buildbucket for the build status
[01:06:55.268] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:55.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:118:0) after 1m0s
[01:07:55.286] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:118:0)
[01:07:55.286] Timer tick, asking Buildbucket for the build status
[01:07:55.489] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:55.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:119:0) after 1m0s
[01:08:55.508] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:119:0)
[01:08:55.508] Timer tick, asking Buildbucket for the build status
[01:08:55.585] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:55.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:120:0) after 1m0s
[01:09:55.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:120:0)
[01:09:55.606] Timer tick, asking Buildbucket for the build status
[01:09:55.656] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:55.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:121:0) after 1m0s
[01:10:55.675] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:121:0)
[01:10:55.675] Timer tick, asking Buildbucket for the build status
[01:10:55.747] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:55.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:122:0) after 1m0s
[01:11:55.766] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:122:0)
[01:11:55.766] Timer tick, asking Buildbucket for the build status
[01:11:56.195] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:56.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:123:0) after 1m0s
[01:12:56.215] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:123:0)
[01:12:56.215] Timer tick, asking Buildbucket for the build status
[01:12:56.293] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:56.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:124:0) after 1m0s
[01:13:56.340] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:124:0)
[01:13:56.340] Timer tick, asking Buildbucket for the build status
[01:13:56.396] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:56.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:125:0) after 1m0s
[01:14:56.417] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:125:0)
[01:14:56.417] Timer tick, asking Buildbucket for the build status
[01:14:56.528] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:56.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:126:0) after 1m0s
[01:15:56.546] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:126:0)
[01:15:56.546] Timer tick, asking Buildbucket for the build status
[01:15:57.880] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:57.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:127:0) after 1m0s
[01:16:57.904] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:127:0)
[01:16:57.904] Timer tick, asking Buildbucket for the build status
[01:16:58.136] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:58.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:128:0) after 1m0s
[01:17:58.158] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:128:0)
[01:17:58.158] Timer tick, asking Buildbucket for the build status
[01:17:58.226] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:58.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:129:0) after 1m0s
[01:18:58.262] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:129:0)
[01:18:58.262] Timer tick, asking Buildbucket for the build status
[01:18:58.354] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:58.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:130:0) after 1m0s
[01:19:58.405] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:130:0)
[01:19:58.405] Timer tick, asking Buildbucket for the build status
[01:19:58.480] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:58.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:131:0) after 1m0s
[01:20:58.521] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:131:0)
[01:20:58.521] Timer tick, asking Buildbucket for the build status
[01:20:58.645] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:58.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:132:0) after 1m0s
[01:21:58.698] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:132:0)
[01:21:58.698] Timer tick, asking Buildbucket for the build status
[01:21:59.239] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:59.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:133:0) after 1m0s
[01:22:59.261] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:133:0)
[01:22:59.261] Timer tick, asking Buildbucket for the build status
[01:22:59.329] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:59.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:134:0) after 1m0s
[01:23:59.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:134:0)
[01:23:59.352] Timer tick, asking Buildbucket for the build status
[01:23:59.576] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:59.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:135:0) after 1m0s
[01:24:59.612] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:135:0)
[01:24:59.612] Timer tick, asking Buildbucket for the build status
[01:24:59.687] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:59.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:136:0) after 1m0s
[01:25:59.750] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:136:0)
[01:25:59.750] Timer tick, asking Buildbucket for the build status
[01:25:59.915] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:59.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:137:0) after 1m0s
[01:26:59.938] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:137:0)
[01:26:59.938] Timer tick, asking Buildbucket for the build status
[01:26:59.983] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:59.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:138:0) after 1m0s
[01:28:00.016] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:138:0)
[01:28:00.016] Timer tick, asking Buildbucket for the build status
[01:28:00.233] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:00.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:139:0) after 1m0s
[01:29:00.257] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:139:0)
[01:29:00.257] Timer tick, asking Buildbucket for the build status
[01:29:00.499] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:00.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:140:0) after 1m0s
[01:30:00.591] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:140:0)
[01:30:00.591] Timer tick, asking Buildbucket for the build status
[01:30:00.691] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:00.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:141:0) after 1m0s
[01:31:00.729] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:141:0)
[01:31:00.729] Timer tick, asking Buildbucket for the build status
[01:31:01.204] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:01.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:142:0) after 1m0s
[01:32:01.233] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:142:0)
[01:32:01.233] Timer tick, asking Buildbucket for the build status
[01:32:01.746] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:01.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:143:0) after 1m0s
[01:33:01.769] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:143:0)
[01:33:01.769] Timer tick, asking Buildbucket for the build status
[01:33:02.687] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:02.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:144:0) after 1m0s
[01:34:02.760] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:144:0)
[01:34:02.760] Timer tick, asking Buildbucket for the build status
[01:34:02.833] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:02.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:145:0) after 1m0s
[01:35:02.857] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:145:0)
[01:35:02.857] Timer tick, asking Buildbucket for the build status
[01:35:02.955] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:02.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:146:0) after 1m0s
[01:36:02.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:146:0)
[01:36:02.981] Timer tick, asking Buildbucket for the build status
[01:36:03.030] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:03.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:147:0) after 1m0s
[01:37:03.062] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:147:0)
[01:37:03.062] Timer tick, asking Buildbucket for the build status
[01:37:03.165] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:03.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:148:0) after 1m0s
[01:38:03.187] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:148:0)
[01:38:03.187] Timer tick, asking Buildbucket for the build status
[01:38:03.271] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:03.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:149:0) after 1m0s
[01:39:03.295] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:149:0)
[01:39:03.295] Timer tick, asking Buildbucket for the build status
[01:39:03.385] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:03.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:150:0) after 1m0s
[01:40:03.405] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:150:0)
[01:40:03.405] Timer tick, asking Buildbucket for the build status
[01:40:03.475] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:03.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:151:0) after 1m0s
[01:41:03.495] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:151:0)
[01:41:03.495] Timer tick, asking Buildbucket for the build status
[01:41:03.587] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:03.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:152:0) after 1m0s
[01:42:03.610] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:152:0)
[01:42:03.610] Timer tick, asking Buildbucket for the build status
[01:42:03.673] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:03.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:153:0) after 1m0s
[01:43:03.741] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:153:0)
[01:43:03.741] Timer tick, asking Buildbucket for the build status
[01:43:03.839] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:03.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:154:0) after 1m0s
[01:44:03.868] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:154:0)
[01:44:03.868] Timer tick, asking Buildbucket for the build status
[01:44:03.917] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:03.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:155:0) after 1m0s
[01:45:03.939] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:155:0)
[01:45:03.939] Timer tick, asking Buildbucket for the build status
[01:45:03.994] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:03.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:156:0) after 1m0s
[01:46:04.016] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:156:0)
[01:46:04.016] Timer tick, asking Buildbucket for the build status
[01:46:04.064] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:04.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:157:0) after 1m0s
[01:47:04.198] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:157:0)
[01:47:04.198] Timer tick, asking Buildbucket for the build status
[01:47:04.352] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:04.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:158:0) after 1m0s
[01:48:04.400] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:158:0)
[01:48:04.400] Timer tick, asking Buildbucket for the build status
[01:48:04.466] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:04.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:159:0) after 1m0s
[01:49:04.489] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:159:0)
[01:49:04.489] Timer tick, asking Buildbucket for the build status
[01:49:04.732] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:04.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:160:0) after 1m0s
[01:50:04.753] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:160:0)
[01:50:04.753] Timer tick, asking Buildbucket for the build status
[01:50:04.809] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:04.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:161:0) after 1m0s
[01:51:04.835] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:161:0)
[01:51:04.835] Timer tick, asking Buildbucket for the build status
[01:51:04.904] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:04.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:162:0) after 1m0s
[01:52:04.929] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:162:0)
[01:52:04.929] Timer tick, asking Buildbucket for the build status
[01:52:04.996] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:04.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:163:0) after 1m0s
[01:53:05.088] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:163:0)
[01:53:05.088] Timer tick, asking Buildbucket for the build status
[01:53:05.139] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:05.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:164:0) after 1m0s
[01:54:05.162] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:164:0)
[01:54:05.162] Timer tick, asking Buildbucket for the build status
[01:54:05.265] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:05.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:165:0) after 1m0s
[01:55:05.290] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:165:0)
[01:55:05.290] Timer tick, asking Buildbucket for the build status
[01:55:05.564] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:05.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:166:0) after 1m0s
[01:56:05.588] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:166:0)
[01:56:05.588] Timer tick, asking Buildbucket for the build status
[01:56:05.659] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:05.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:167:0) after 1m0s
[01:57:05.777] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:167:0)
[01:57:05.777] Timer tick, asking Buildbucket for the build status
[01:57:05.826] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:05.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:168:0) after 1m0s
[01:58:05.864] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:168:0)
[01:58:05.864] Timer tick, asking Buildbucket for the build status
[01:58:06.061] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:06.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:169:0) after 1m0s
[01:59:06.093] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:169:0)
[01:59:06.093] Timer tick, asking Buildbucket for the build status
[01:59:06.248] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:06.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:170:0) after 1m0s
[02:00:06.295] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:170:0)
[02:00:06.295] Timer tick, asking Buildbucket for the build status
[02:00:06.474] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:06.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:171:0) after 1m0s
[02:01:06.514] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:171:0)
[02:01:06.514] Timer tick, asking Buildbucket for the build status
[02:01:06.710] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:06.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:172:0) after 1m0s
[02:02:06.736] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:172:0)
[02:02:06.736] Timer tick, asking Buildbucket for the build status
[02:02:06.855] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:06.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:173:0) after 1m0s
[02:03:06.882] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:173:0)
[02:03:06.882] Timer tick, asking Buildbucket for the build status
[02:03:07.031] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:07.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:174:0) after 1m0s
[02:04:07.057] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:174:0)
[02:04:07.057] Timer tick, asking Buildbucket for the build status
[02:04:07.138] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:07.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:175:0) after 1m0s
[02:05:07.247] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:175:0)
[02:05:07.247] Timer tick, asking Buildbucket for the build status
[02:05:07.335] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:07.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:176:0) after 1m0s
[02:06:07.370] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:176:0)
[02:06:07.370] Timer tick, asking Buildbucket for the build status
[02:06:07.445] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:07.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:177:0) after 1m0s
[02:07:07.482] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:177:0)
[02:07:07.482] Timer tick, asking Buildbucket for the build status
[02:07:07.864] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:178:0) after 1m0s
[02:08:07.886] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:178:0)
[02:08:07.886] Timer tick, asking Buildbucket for the build status
[02:08:07.999] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:07.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:179:0) after 1m0s
[02:09:08.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:179:0)
[02:09:08.029] Timer tick, asking Buildbucket for the build status
[02:09:08.138] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:08.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:180:0) after 1m0s
[02:10:08.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:180:0)
[02:10:08.242] Timer tick, asking Buildbucket for the build status
[02:10:08.531] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:08.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:181:0) after 1m0s
[02:11:08.551] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:181:0)
[02:11:08.551] Timer tick, asking Buildbucket for the build status
[02:11:08.637] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:08.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:182:0) after 1m0s
[02:12:08.659] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:182:0)
[02:12:08.659] Timer tick, asking Buildbucket for the build status
[02:12:08.860] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:08.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:183:0) after 1m0s
[02:13:08.952] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:183:0)
[02:13:08.952] Timer tick, asking Buildbucket for the build status
[02:13:09.000] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:09.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:184:0) after 1m0s
[02:14:09.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:184:0)
[02:14:09.029] Timer tick, asking Buildbucket for the build status
[02:14:09.149] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:09.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:185:0) after 1m0s
[02:15:09.179] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:185:0)
[02:15:09.179] Timer tick, asking Buildbucket for the build status
[02:15:09.318] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:09.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:186:0) after 1m0s
[02:16:09.362] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:186:0)
[02:16:09.362] Timer tick, asking Buildbucket for the build status
[02:16:09.486] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:09.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:187:0) after 1m0s
[02:17:09.506] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:187:0)
[02:17:09.506] Timer tick, asking Buildbucket for the build status
[02:17:09.548] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:09.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:188:0) after 1m0s
[02:18:09.572] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:188:0)
[02:18:09.572] Timer tick, asking Buildbucket for the build status
[02:18:09.682] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:09.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:189:0) after 1m0s
[02:19:09.704] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:189:0)
[02:19:09.704] Timer tick, asking Buildbucket for the build status
[02:19:09.765] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:09.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:190:0) after 1m0s
[02:20:09.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:190:0)
[02:20:09.786] Timer tick, asking Buildbucket for the build status
[02:20:09.873] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:09.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:191:0) after 1m0s
[02:21:09.893] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:191:0)
[02:21:09.893] Timer tick, asking Buildbucket for the build status
[02:21:10.132] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:10.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:192:0) after 1m0s
[02:22:10.208] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:192:0)
[02:22:10.208] Timer tick, asking Buildbucket for the build status
[02:22:10.260] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:10.260] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:193:0) after 1m0s
[02:23:10.293] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:193:0)
[02:23:10.293] Timer tick, asking Buildbucket for the build status
[02:23:10.350] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:10.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:194:0) after 1m0s
[02:24:10.373] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:194:0)
[02:24:10.373] Timer tick, asking Buildbucket for the build status
[02:24:10.502] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:10.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:195:0) after 1m0s
[02:25:10.598] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:195:0)
[02:25:10.598] Timer tick, asking Buildbucket for the build status
[02:25:10.666] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:10.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:196:0) after 1m0s
[02:26:10.693] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:196:0)
[02:26:10.693] Timer tick, asking Buildbucket for the build status
[02:26:10.860] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:10.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:197:0) after 1m0s
[02:27:10.939] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:197:0)
[02:27:10.939] Timer tick, asking Buildbucket for the build status
[02:27:11.067] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:11.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:198:0) after 1m0s
[02:28:11.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:198:0)
[02:28:11.117] Timer tick, asking Buildbucket for the build status
[02:28:11.180] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:11.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:199:0) after 1m0s
[02:29:11.203] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:199:0)
[02:29:11.203] Timer tick, asking Buildbucket for the build status
[02:29:11.318] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:11.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:200:0) after 1m0s
[02:30:11.342] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:200:0)
[02:30:11.342] Timer tick, asking Buildbucket for the build status
[02:30:11.389] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:11.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:201:0) after 1m0s
[02:31:11.419] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:201:0)
[02:31:11.419] Timer tick, asking Buildbucket for the build status
[02:31:11.477] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:11.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:202:0) after 1m0s
[02:32:11.498] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:202:0)
[02:32:11.498] Timer tick, asking Buildbucket for the build status
[02:32:11.576] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:11.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:203:0) after 1m0s
[02:33:11.602] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:203:0)
[02:33:11.602] Timer tick, asking Buildbucket for the build status
[02:33:11.659] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:11.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:204:0) after 1m0s
[02:34:11.698] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:204:0)
[02:34:11.698] Timer tick, asking Buildbucket for the build status
[02:34:11.783] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:11.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:205:0) after 1m0s
[02:35:11.855] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:205:0)
[02:35:11.855] Timer tick, asking Buildbucket for the build status
[02:35:11.925] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:11.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:206:0) after 1m0s
[02:36:11.954] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:206:0)
[02:36:11.954] Timer tick, asking Buildbucket for the build status
[02:36:12.187] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:12.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:207:0) after 1m0s
[02:37:12.558] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:207:0)
[02:37:12.558] Timer tick, asking Buildbucket for the build status
[02:37:12.912] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:12.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:208:0) after 1m0s
[02:38:12.945] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:208:0)
[02:38:12.945] Timer tick, asking Buildbucket for the build status
[02:38:13.013] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:13.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:209:0) after 1m0s
[02:39:13.049] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:209:0)
[02:39:13.049] Timer tick, asking Buildbucket for the build status
[02:39:13.118] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:13.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:210:0) after 1m0s
[02:40:13.177] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:210:0)
[02:40:13.177] Timer tick, asking Buildbucket for the build status
[02:40:13.263] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:13.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:211:0) after 1m0s
[02:41:13.292] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:211:0)
[02:41:13.292] Timer tick, asking Buildbucket for the build status
[02:41:13.354] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:13.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:212:0) after 1m0s
[02:42:13.393] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:212:0)
[02:42:13.393] Timer tick, asking Buildbucket for the build status
[02:42:13.518] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:13.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:213:0) after 1m0s
[02:43:13.547] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:213:0)
[02:43:13.547] Timer tick, asking Buildbucket for the build status
[02:43:13.695] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:13.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:214:0) after 1m0s
[02:44:13.731] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:214:0)
[02:44:13.732] Timer tick, asking Buildbucket for the build status
[02:44:14.064] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:14.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:215:0) after 1m0s
[02:45:14.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:215:0)
[02:45:14.123] Timer tick, asking Buildbucket for the build status
[02:45:14.193] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:14.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:216:0) after 1m0s
[02:46:14.351] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:216:0)
[02:46:14.351] Timer tick, asking Buildbucket for the build status
[02:46:14.545] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:14.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:217:0) after 1m0s
[02:47:14.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:217:0)
[02:47:14.567] Timer tick, asking Buildbucket for the build status
[02:47:14.759] Build 8924133303257135328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:14.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9089563365676301872:218:0) after 1m0s
[02:47:40.113] Received PubSub notification, asking Buildbucket for the build status
[02:47:40.213] Build 8924133303257135328: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:47:40.213] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1547693258952726",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547680294706534",
    "failure_reason": "BUILD_FAILURE",
    "id": "8924133303257135328",
    "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\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure predownload validator corpus\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3996-e2326f7\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-east1-b-rnlj\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 93, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547680294706534, \"tags\": [\"builder:linux-64-validator-opt\", \"buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089563365676301872\", \"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\": \"8924133303257135328\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"787866ca5f2a69470998b1e07cfc1426e2a41260\", \"bot_id\": \"gce-trusty-e833d7b0-us-east1-b-rnlj\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1547680797864140",
    "status": "COMPLETED",
    "status_changed_ts": "1547693259671605",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/93",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089563365676301872",
      "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/93",
      "swarming_tag:buildbucket_bucket:nacl/ci",
      "swarming_tag:buildbucket_build_id:8924133303257135328",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
      "swarming_tag:builder:linux-64-validator-opt",
      "swarming_tag:buildset:commit/git/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/787866ca5f2a69470998b1e07cfc1426e2a41260",
      "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/8924133303257135328/+/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:9089563365676301872",
      "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:e1d5da8663c0550abd5affb209a08bfdb819ce3f",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4271c0b1a9302b10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547693259673370",
    "url": "https://ci.chromium.org/b/8924133303257135328",
    "utcnow_ts": "1547693260195345"
  }
}
[02:47:40.213] Invocation finished in 3h36m7.197610798s with status FAILED