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

Status: FAILED

Triggered by: -

Duration: 3 hours

Actions:

Definition (rev 8fecfaafc1abba4843234d58a712c5811f9e1de9)

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

Incoming triggers

15362ac60573769f6606ab1d1c11668052e411a7 (6 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[20:31:24.334] New invocation is queued and will start shortly
[20:31:25.573] Starting the invocation (attempt 1)
[20:31:25.644] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:31:25.644] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:31:25.644] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9086946129101801696",
  "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\":\"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
  "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:9086946129101801696",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
    "gitiles_ref:refs/heads/master"
  ]
}
[20:31:27.267] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1550176285775489",
    "id": "8921516066925378000",
    "lease_expiration_ts": "1550327486805215",
    "lease_key": "2098663444",
    "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\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1550176287027525",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/102",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9086946129101801696",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8921516066925378000/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:4306867eb4350110",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1550176287027662",
    "url": "https://ci.chromium.org/b/8921516066925378000",
    "utcnow_ts": "1550176287258129"
  }
}
[20:31:27.267] Task URL: https://ci.chromium.org/b/8921516066925378000
[20:31:27.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:2:0) after 1m0s
[20:32:22.408] Received PubSub notification, asking Buildbucket for the build status
[20:32:22.501] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:27.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:2:0)
[20:32:27.298] Timer tick, asking Buildbucket for the build status
[20:32:27.358] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:27.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:4:0) after 1m0s
[20:33:27.379] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:4:0)
[20:33:27.379] Timer tick, asking Buildbucket for the build status
[20:33:27.622] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:27.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:5:0) after 1m0s
[20:34:27.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:5:0)
[20:34:27.640] Timer tick, asking Buildbucket for the build status
[20:34:27.740] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:27.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:6:0) after 1m0s
[20:35:27.813] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:6:0)
[20:35:27.813] Timer tick, asking Buildbucket for the build status
[20:35:27.982] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:27.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:7:0) after 1m0s
[20:36:27.997] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:7:0)
[20:36:27.997] Timer tick, asking Buildbucket for the build status
[20:36:28.097] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:28.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:8:0) after 1m0s
[20:37:28.112] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:8:0)
[20:37:28.112] Timer tick, asking Buildbucket for the build status
[20:37:28.774] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:28.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:9:0) after 1m0s
[20:38:28.791] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:9:0)
[20:38:28.791] Timer tick, asking Buildbucket for the build status
[20:38:28.840] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:28.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:10:0) after 1m0s
[20:39:28.857] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:10:0)
[20:39:28.857] Timer tick, asking Buildbucket for the build status
[20:39:28.915] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:28.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:11:0) after 1m0s
[20:40:28.930] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:11:0)
[20:40:28.930] Timer tick, asking Buildbucket for the build status
[20:40:29.028] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:29.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:12:0) after 1m0s
[20:41:29.044] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:12:0)
[20:41:29.044] Timer tick, asking Buildbucket for the build status
[20:41:29.516] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:29.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:13:0) after 1m0s
[20:42:29.531] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:13:0)
[20:42:29.531] Timer tick, asking Buildbucket for the build status
[20:42:29.657] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:29.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:14:0) after 1m0s
[20:43:29.674] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:14:0)
[20:43:29.674] Timer tick, asking Buildbucket for the build status
[20:43:29.969] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:29.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:15:0) after 1m0s
[20:44:29.984] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:15:0)
[20:44:29.984] Timer tick, asking Buildbucket for the build status
[20:44:30.080] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:30.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:16:0) after 1m0s
[20:45:30.101] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:16:0)
[20:45:30.101] Timer tick, asking Buildbucket for the build status
[20:45:30.291] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:30.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:17:0) after 1m0s
[20:46:30.309] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:17:0)
[20:46:30.309] Timer tick, asking Buildbucket for the build status
[20:46:30.642] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:30.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:18:0) after 1m0s
[20:47:30.663] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:18:0)
[20:47:30.663] Timer tick, asking Buildbucket for the build status
[20:47:30.902] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:30.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:19:0) after 1m0s
[20:48:30.918] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:19:0)
[20:48:30.918] Timer tick, asking Buildbucket for the build status
[20:48:30.963] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:30.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:20:0) after 1m0s
[20:49:30.978] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:20:0)
[20:49:30.978] Timer tick, asking Buildbucket for the build status
[20:49:31.041] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:31.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:21:0) after 1m0s
[20:50:31.143] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:21:0)
[20:50:31.143] Timer tick, asking Buildbucket for the build status
[20:50:31.202] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:31.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:22:0) after 1m0s
[20:51:31.217] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:22:0)
[20:51:31.217] Timer tick, asking Buildbucket for the build status
[20:51:31.281] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:31.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:23:0) after 1m0s
[20:52:31.297] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:23:0)
[20:52:31.297] Timer tick, asking Buildbucket for the build status
[20:52:31.670] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:31.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:24:0) after 1m0s
[20:53:31.688] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:24:0)
[20:53:31.688] Timer tick, asking Buildbucket for the build status
[20:53:31.740] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:31.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:25:0) after 1m0s
[20:54:31.765] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:25:0)
[20:54:31.765] Timer tick, asking Buildbucket for the build status
[20:54:31.864] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:31.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:26:0) after 1m0s
[20:55:31.902] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:26:0)
[20:55:31.902] Timer tick, asking Buildbucket for the build status
[20:55:31.978] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:31.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:27:0) after 1m0s
[20:56:31.998] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:27:0)
[20:56:31.998] Timer tick, asking Buildbucket for the build status
[20:56:32.037] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:32.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:28:0) after 1m0s
[20:57:32.068] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:28:0)
[20:57:32.068] Timer tick, asking Buildbucket for the build status
[20:57:32.654] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:32.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:29:0) after 1m0s
[20:58:32.671] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:29:0)
[20:58:32.671] Timer tick, asking Buildbucket for the build status
[20:58:32.736] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:32.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:30:0) after 1m0s
[20:59:32.754] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:30:0)
[20:59:32.754] Timer tick, asking Buildbucket for the build status
[20:59:32.803] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:32.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:31:0) after 1m0s
[21:00:32.825] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:31:0)
[21:00:32.825] Timer tick, asking Buildbucket for the build status
[21:00:32.990] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:32.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:32:0) after 1m0s
[21:01:33.007] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:32:0)
[21:01:33.007] Timer tick, asking Buildbucket for the build status
[21:01:33.140] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:33.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:33:0) after 1m0s
[21:02:33.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:33:0)
[21:02:33.157] Timer tick, asking Buildbucket for the build status
[21:02:33.212] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:33.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:34:0) after 1m0s
[21:03:33.228] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:34:0)
[21:03:33.229] Timer tick, asking Buildbucket for the build status
[21:03:33.338] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:33.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:35:0) after 1m0s
[21:04:33.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:35:0)
[21:04:33.394] Timer tick, asking Buildbucket for the build status
[21:04:33.494] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:33.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:36:0) after 1m0s
[21:05:33.519] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:36:0)
[21:05:33.519] Timer tick, asking Buildbucket for the build status
[21:05:33.609] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:33.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:37:0) after 1m0s
[21:06:33.625] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:37:0)
[21:06:33.625] Timer tick, asking Buildbucket for the build status
[21:06:33.777] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:33.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:38:0) after 1m0s
[21:07:33.796] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:38:0)
[21:07:33.796] Timer tick, asking Buildbucket for the build status
[21:07:33.868] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:33.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:39:0) after 1m0s
[21:08:33.896] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:39:0)
[21:08:33.896] Timer tick, asking Buildbucket for the build status
[21:08:34.096] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:34.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:40:0) after 1m0s
[21:09:34.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:40:0)
[21:09:34.113] Timer tick, asking Buildbucket for the build status
[21:09:35.289] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:35.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:41:0) after 1m0s
[21:10:35.365] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:41:0)
[21:10:35.365] Timer tick, asking Buildbucket for the build status
[21:10:35.825] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:35.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:42:0) after 1m0s
[21:11:35.841] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:42:0)
[21:11:35.841] Timer tick, asking Buildbucket for the build status
[21:11:35.972] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:35.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:43:0) after 1m0s
[21:12:35.989] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:43:0)
[21:12:35.989] Timer tick, asking Buildbucket for the build status
[21:12:36.254] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:36.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:44:0) after 1m0s
[21:13:36.270] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:44:0)
[21:13:36.270] Timer tick, asking Buildbucket for the build status
[21:13:36.750] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:36.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:45:0) after 1m0s
[21:14:36.772] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:45:0)
[21:14:36.772] Timer tick, asking Buildbucket for the build status
[21:14:37.289] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:37.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:46:0) after 1m0s
[21:15:37.312] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:46:0)
[21:15:37.312] Timer tick, asking Buildbucket for the build status
[21:15:37.532] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:37.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:47:0) after 1m0s
[21:16:37.550] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:47:0)
[21:16:37.550] Timer tick, asking Buildbucket for the build status
[21:16:37.621] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:37.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:48:0) after 1m0s
[21:17:37.649] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:48:0)
[21:17:37.649] Timer tick, asking Buildbucket for the build status
[21:17:37.977] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:37.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:49:0) after 1m0s
[21:18:37.995] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:49:0)
[21:18:37.995] Timer tick, asking Buildbucket for the build status
[21:18:38.650] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:38.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:50:0) after 1m0s
[21:19:38.668] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:50:0)
[21:19:38.668] Timer tick, asking Buildbucket for the build status
[21:19:39.582] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:39.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:51:0) after 1m0s
[21:20:39.599] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:51:0)
[21:20:39.599] Timer tick, asking Buildbucket for the build status
[21:20:39.909] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:39.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:52:0) after 1m0s
[21:21:39.925] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:52:0)
[21:21:39.925] Timer tick, asking Buildbucket for the build status
[21:21:40.078] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:40.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:53:0) after 1m0s
[21:22:40.101] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:53:0)
[21:22:40.101] Timer tick, asking Buildbucket for the build status
[21:22:40.417] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:40.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:54:0) after 1m0s
[21:23:40.434] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:54:0)
[21:23:40.434] Timer tick, asking Buildbucket for the build status
[21:23:40.847] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:40.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:55:0) after 1m0s
[21:24:40.863] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:55:0)
[21:24:40.863] Timer tick, asking Buildbucket for the build status
[21:24:41.446] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:41.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:56:0) after 1m0s
[21:25:41.464] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:56:0)
[21:25:41.464] Timer tick, asking Buildbucket for the build status
[21:25:42.091] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:42.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:57:0) after 1m0s
[21:26:42.399] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:57:0)
[21:26:42.399] Timer tick, asking Buildbucket for the build status
[21:26:43.075] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:43.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:58:0) after 1m0s
[21:27:43.095] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:58:0)
[21:27:43.095] Timer tick, asking Buildbucket for the build status
[21:27:43.350] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:43.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:59:0) after 1m0s
[21:28:43.376] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:59:0)
[21:28:43.376] Timer tick, asking Buildbucket for the build status
[21:28:44.139] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:44.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:60:0) after 1m0s
[21:29:44.155] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:60:0)
[21:29:44.155] Timer tick, asking Buildbucket for the build status
[21:29:44.236] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:44.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:61:0) after 1m0s
[21:30:44.258] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:61:0)
[21:30:44.258] Timer tick, asking Buildbucket for the build status
[21:30:45.001] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:45.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:62:0) after 1m0s
[21:31:45.021] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:62:0)
[21:31:45.021] Timer tick, asking Buildbucket for the build status
[21:31:45.085] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:45.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:63:0) after 1m0s
[21:32:45.106] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:63:0)
[21:32:45.106] Timer tick, asking Buildbucket for the build status
[21:32:45.929] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:45.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:64:0) after 1m0s
[21:33:45.944] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:64:0)
[21:33:45.944] Timer tick, asking Buildbucket for the build status
[21:33:46.659] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:46.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:65:0) after 1m0s
[21:34:46.681] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:65:0)
[21:34:46.681] Timer tick, asking Buildbucket for the build status
[21:34:47.149] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:47.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:66:0) after 1m0s
[21:35:47.170] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:66:0)
[21:35:47.170] Timer tick, asking Buildbucket for the build status
[21:35:47.347] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:47.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:67:0) after 1m0s
[21:36:47.375] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:67:0)
[21:36:47.375] Timer tick, asking Buildbucket for the build status
[21:36:47.983] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:47.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:68:0) after 1m0s
[21:37:48.001] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:68:0)
[21:37:48.001] Timer tick, asking Buildbucket for the build status
[21:37:48.966] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:48.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:69:0) after 1m0s
[21:38:49.083] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:69:0)
[21:38:49.083] Timer tick, asking Buildbucket for the build status
[21:38:50.105] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:50.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:70:0) after 1m0s
[21:39:50.128] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:70:0)
[21:39:50.128] Timer tick, asking Buildbucket for the build status
[21:39:50.448] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:50.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:71:0) after 1m0s
[21:40:50.601] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:71:0)
[21:40:50.601] Timer tick, asking Buildbucket for the build status
[21:40:51.168] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:51.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:72:0) after 1m0s
[21:41:51.187] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:72:0)
[21:41:51.187] Timer tick, asking Buildbucket for the build status
[21:41:51.403] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:51.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:73:0) after 1m0s
[21:42:51.435] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:73:0)
[21:42:51.435] Timer tick, asking Buildbucket for the build status
[21:42:51.685] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:51.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:74:0) after 1m0s
[21:43:51.706] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:74:0)
[21:43:51.706] Timer tick, asking Buildbucket for the build status
[21:43:52.441] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:52.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:75:0) after 1m0s
[21:44:52.457] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:75:0)
[21:44:52.457] Timer tick, asking Buildbucket for the build status
[21:44:53.173] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:53.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:76:0) after 1m0s
[21:45:53.251] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:76:0)
[21:45:53.251] Timer tick, asking Buildbucket for the build status
[21:45:53.487] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:53.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:77:0) after 1m0s
[21:46:53.556] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:77:0)
[21:46:53.556] Timer tick, asking Buildbucket for the build status
[21:46:54.511] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:54.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:78:0) after 1m0s
[21:47:54.543] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:78:0)
[21:47:54.543] Timer tick, asking Buildbucket for the build status
[21:47:54.634] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:54.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:79:0) after 1m0s
[21:48:54.657] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:79:0)
[21:48:54.657] Timer tick, asking Buildbucket for the build status
[21:48:55.146] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:55.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:80:0) after 1m0s
[21:49:55.163] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:80:0)
[21:49:55.163] Timer tick, asking Buildbucket for the build status
[21:49:55.217] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:55.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:81:0) after 1m0s
[21:50:55.241] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:81:0)
[21:50:55.241] Timer tick, asking Buildbucket for the build status
[21:50:55.475] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:55.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:82:0) after 1m0s
[21:51:55.493] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:82:0)
[21:51:55.493] Timer tick, asking Buildbucket for the build status
[21:51:56.155] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:56.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:83:0) after 1m0s
[21:52:56.174] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:83:0)
[21:52:56.174] Timer tick, asking Buildbucket for the build status
[21:52:56.392] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:56.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:84:0) after 1m0s
[21:53:56.412] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:84:0)
[21:53:56.412] Timer tick, asking Buildbucket for the build status
[21:53:56.495] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:56.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:85:0) after 1m0s
[21:54:56.512] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:85:0)
[21:54:56.512] Timer tick, asking Buildbucket for the build status
[21:54:56.665] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:56.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:86:0) after 1m0s
[21:55:56.683] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:86:0)
[21:55:56.683] Timer tick, asking Buildbucket for the build status
[21:55:56.737] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:56.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:87:0) after 1m0s
[21:56:56.780] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:87:0)
[21:56:56.780] Timer tick, asking Buildbucket for the build status
[21:56:56.820] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:56.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:88:0) after 1m0s
[21:57:56.840] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:88:0)
[21:57:56.840] Timer tick, asking Buildbucket for the build status
[21:57:56.967] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:56.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:89:0) after 1m0s
[21:58:56.985] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:89:0)
[21:58:56.985] Timer tick, asking Buildbucket for the build status
[21:58:57.089] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:57.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:90:0) after 1m0s
[21:59:57.107] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:90:0)
[21:59:57.107] Timer tick, asking Buildbucket for the build status
[21:59:57.316] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:57.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:91:0) after 1m0s
[22:00:57.334] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:91:0)
[22:00:57.334] Timer tick, asking Buildbucket for the build status
[22:00:57.453] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:57.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:92:0) after 1m0s
[22:01:57.511] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:92:0)
[22:01:57.511] Timer tick, asking Buildbucket for the build status
[22:01:57.568] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:57.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:93:0) after 1m0s
[22:02:57.604] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:93:0)
[22:02:57.604] Timer tick, asking Buildbucket for the build status
[22:02:58.502] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:58.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:94:0) after 1m0s
[22:03:58.521] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:94:0)
[22:03:58.521] Timer tick, asking Buildbucket for the build status
[22:03:58.675] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:58.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:95:0) after 1m0s
[22:04:58.695] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:95:0)
[22:04:58.695] Timer tick, asking Buildbucket for the build status
[22:04:59.632] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:59.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:96:0) after 1m0s
[22:05:59.668] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:96:0)
[22:05:59.668] Timer tick, asking Buildbucket for the build status
[22:05:59.710] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:59.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:97:0) after 1m0s
[22:06:59.732] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:97:0)
[22:06:59.732] Timer tick, asking Buildbucket for the build status
[22:07:00.049] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:00.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:98:0) after 1m0s
[22:08:00.068] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:98:0)
[22:08:00.068] Timer tick, asking Buildbucket for the build status
[22:08:00.400] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:00.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:99:0) after 1m0s
[22:09:00.418] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:99:0)
[22:09:00.418] Timer tick, asking Buildbucket for the build status
[22:09:00.707] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:00.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:100:0) after 1m0s
[22:10:00.743] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:100:0)
[22:10:00.743] Timer tick, asking Buildbucket for the build status
[22:10:00.945] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:00.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:101:0) after 1m0s
[22:11:01.017] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:101:0)
[22:11:01.017] Timer tick, asking Buildbucket for the build status
[22:11:01.537] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:01.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:102:0) after 1m0s
[22:12:01.554] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:102:0)
[22:12:01.554] Timer tick, asking Buildbucket for the build status
[22:12:01.617] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:01.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:103:0) after 1m0s
[22:13:01.645] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:103:0)
[22:13:01.645] Timer tick, asking Buildbucket for the build status
[22:13:01.830] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:01.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:104:0) after 1m0s
[22:14:01.850] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:104:0)
[22:14:01.850] Timer tick, asking Buildbucket for the build status
[22:14:02.067] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:02.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:105:0) after 1m0s
[22:15:02.099] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:105:0)
[22:15:02.099] Timer tick, asking Buildbucket for the build status
[22:15:02.734] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:02.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:106:0) after 1m0s
[22:16:02.775] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:106:0)
[22:16:02.775] Timer tick, asking Buildbucket for the build status
[22:16:03.796] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:03.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:107:0) after 1m0s
[22:17:03.902] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:107:0)
[22:17:03.902] Timer tick, asking Buildbucket for the build status
[22:17:05.240] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:05.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:108:0) after 1m0s
[22:18:05.267] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:108:0)
[22:18:05.267] Timer tick, asking Buildbucket for the build status
[22:18:05.465] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:05.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:109:0) after 1m0s
[22:19:05.605] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:109:0)
[22:19:05.605] Timer tick, asking Buildbucket for the build status
[22:19:05.867] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:05.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:110:0) after 1m0s
[22:20:05.885] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:110:0)
[22:20:05.885] Timer tick, asking Buildbucket for the build status
[22:20:06.025] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:06.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:111:0) after 1m0s
[22:21:06.043] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:111:0)
[22:21:06.043] Timer tick, asking Buildbucket for the build status
[22:21:06.712] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:06.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:112:0) after 1m0s
[22:22:06.730] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:112:0)
[22:22:06.730] Timer tick, asking Buildbucket for the build status
[22:22:07.031] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:07.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:113:0) after 1m0s
[22:23:07.047] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:113:0)
[22:23:07.047] Timer tick, asking Buildbucket for the build status
[22:23:07.234] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:07.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:114:0) after 1m0s
[22:24:07.270] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:114:0)
[22:24:07.270] Timer tick, asking Buildbucket for the build status
[22:24:07.640] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:07.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:115:0) after 1m0s
[22:25:07.660] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:115:0)
[22:25:07.660] Timer tick, asking Buildbucket for the build status
[22:25:08.144] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:08.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:116:0) after 1m0s
[22:26:08.195] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:116:0)
[22:26:08.195] Timer tick, asking Buildbucket for the build status
[22:26:08.330] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:08.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:117:0) after 1m0s
[22:27:08.348] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:117:0)
[22:27:08.348] Timer tick, asking Buildbucket for the build status
[22:27:08.425] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:08.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:118:0) after 1m0s
[22:28:08.446] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:118:0)
[22:28:08.446] Timer tick, asking Buildbucket for the build status
[22:28:08.500] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:08.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:119:0) after 1m0s
[22:29:08.520] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:119:0)
[22:29:08.520] Timer tick, asking Buildbucket for the build status
[22:29:08.678] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:08.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:120:0) after 1m0s
[22:30:08.698] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:120:0)
[22:30:08.698] Timer tick, asking Buildbucket for the build status
[22:30:11.453] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:11.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:121:0) after 1m0s
[22:31:11.474] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:121:0)
[22:31:11.474] Timer tick, asking Buildbucket for the build status
[22:31:11.614] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:11.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:122:0) after 1m0s
[22:32:11.633] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:122:0)
[22:32:11.633] Timer tick, asking Buildbucket for the build status
[22:32:11.763] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:11.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:123:0) after 1m0s
[22:33:11.810] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:123:0)
[22:33:11.810] Timer tick, asking Buildbucket for the build status
[22:33:11.857] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:11.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:124:0) after 1m0s
[22:34:11.876] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:124:0)
[22:34:11.876] Timer tick, asking Buildbucket for the build status
[22:34:12.249] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:12.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:125:0) after 1m0s
[22:35:12.269] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:125:0)
[22:35:12.269] Timer tick, asking Buildbucket for the build status
[22:35:12.467] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:12.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:126:0) after 1m0s
[22:36:12.485] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:126:0)
[22:36:12.485] Timer tick, asking Buildbucket for the build status
[22:36:12.535] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:12.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:127:0) after 1m0s
[22:37:12.586] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:127:0)
[22:37:12.586] Timer tick, asking Buildbucket for the build status
[22:37:12.640] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:12.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:128:0) after 1m0s
[22:38:12.659] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:128:0)
[22:38:12.659] Timer tick, asking Buildbucket for the build status
[22:38:12.727] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:12.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:129:0) after 1m0s
[22:39:12.745] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:129:0)
[22:39:12.745] Timer tick, asking Buildbucket for the build status
[22:39:12.894] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:12.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:130:0) after 1m0s
[22:40:12.912] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:130:0)
[22:40:12.912] Timer tick, asking Buildbucket for the build status
[22:40:12.967] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:12.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:131:0) after 1m0s
[22:41:12.984] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:131:0)
[22:41:12.984] Timer tick, asking Buildbucket for the build status
[22:41:13.355] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:13.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:132:0) after 1m0s
[22:42:13.376] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:132:0)
[22:42:13.376] Timer tick, asking Buildbucket for the build status
[22:42:13.426] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:13.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:133:0) after 1m0s
[22:43:13.446] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:133:0)
[22:43:13.446] Timer tick, asking Buildbucket for the build status
[22:43:13.509] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:13.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:134:0) after 1m0s
[22:44:13.527] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:134:0)
[22:44:13.527] Timer tick, asking Buildbucket for the build status
[22:44:13.935] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:13.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:135:0) after 1m0s
[22:45:13.952] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:135:0)
[22:45:13.952] Timer tick, asking Buildbucket for the build status
[22:45:14.021] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:14.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:136:0) after 1m0s
[22:46:14.039] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:136:0)
[22:46:14.039] Timer tick, asking Buildbucket for the build status
[22:46:14.099] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:14.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:137:0) after 1m0s
[22:47:14.120] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:137:0)
[22:47:14.120] Timer tick, asking Buildbucket for the build status
[22:47:14.312] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:14.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:138:0) after 1m0s
[22:48:14.345] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:138:0)
[22:48:14.345] Timer tick, asking Buildbucket for the build status
[22:48:14.402] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:14.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:139:0) after 1m0s
[22:49:14.425] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:139:0)
[22:49:14.425] Timer tick, asking Buildbucket for the build status
[22:49:14.745] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:14.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:140:0) after 1m0s
[22:50:14.763] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:140:0)
[22:50:14.763] Timer tick, asking Buildbucket for the build status
[22:50:14.905] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:14.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:141:0) after 1m0s
[22:51:14.945] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:141:0)
[22:51:14.945] Timer tick, asking Buildbucket for the build status
[22:51:15.076] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:15.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:142:0) after 1m0s
[22:52:15.126] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:142:0)
[22:52:15.126] Timer tick, asking Buildbucket for the build status
[22:52:15.277] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:15.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:143:0) after 1m0s
[22:53:15.303] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:143:0)
[22:53:15.303] Timer tick, asking Buildbucket for the build status
[22:53:15.457] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:15.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:144:0) after 1m0s
[22:54:15.492] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:144:0)
[22:54:15.492] Timer tick, asking Buildbucket for the build status
[22:54:15.555] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:15.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:145:0) after 1m0s
[22:55:15.575] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:145:0)
[22:55:15.575] Timer tick, asking Buildbucket for the build status
[22:55:15.655] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:15.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:146:0) after 1m0s
[22:56:15.673] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:146:0)
[22:56:15.673] Timer tick, asking Buildbucket for the build status
[22:56:15.733] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:15.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:147:0) after 1m0s
[22:57:15.751] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:147:0)
[22:57:15.751] Timer tick, asking Buildbucket for the build status
[22:57:15.810] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:15.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:148:0) after 1m0s
[22:58:15.838] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:148:0)
[22:58:15.838] Timer tick, asking Buildbucket for the build status
[22:58:15.982] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:15.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:149:0) after 1m0s
[22:59:16.002] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:149:0)
[22:59:16.002] Timer tick, asking Buildbucket for the build status
[22:59:16.222] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:16.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:150:0) after 1m0s
[23:00:16.241] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:150:0)
[23:00:16.241] Timer tick, asking Buildbucket for the build status
[23:00:16.296] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:16.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:151:0) after 1m0s
[23:01:16.319] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:151:0)
[23:01:16.319] Timer tick, asking Buildbucket for the build status
[23:01:16.507] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:16.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:152:0) after 1m0s
[23:02:16.527] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:152:0)
[23:02:16.527] Timer tick, asking Buildbucket for the build status
[23:02:16.659] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:16.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:153:0) after 1m0s
[23:03:16.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:153:0)
[23:03:16.679] Timer tick, asking Buildbucket for the build status
[23:03:16.855] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:16.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:154:0) after 1m0s
[23:04:16.876] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:154:0)
[23:04:16.876] Timer tick, asking Buildbucket for the build status
[23:04:17.198] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:17.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:155:0) after 1m0s
[23:05:17.221] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:155:0)
[23:05:17.221] Timer tick, asking Buildbucket for the build status
[23:05:17.291] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:17.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:156:0) after 1m0s
[23:06:17.313] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:156:0)
[23:06:17.313] Timer tick, asking Buildbucket for the build status
[23:06:17.452] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:17.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:157:0) after 1m0s
[23:07:17.474] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:157:0)
[23:07:17.474] Timer tick, asking Buildbucket for the build status
[23:07:17.825] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:17.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:158:0) after 1m0s
[23:08:17.847] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:158:0)
[23:08:17.847] Timer tick, asking Buildbucket for the build status
[23:08:17.906] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:17.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:159:0) after 1m0s
[23:09:17.926] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:159:0)
[23:09:17.926] Timer tick, asking Buildbucket for the build status
[23:09:18.096] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:18.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:160:0) after 1m0s
[23:10:18.166] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:160:0)
[23:10:18.166] Timer tick, asking Buildbucket for the build status
[23:10:18.410] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:18.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:161:0) after 1m0s
[23:11:18.433] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:161:0)
[23:11:18.433] Timer tick, asking Buildbucket for the build status
[23:11:18.509] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:162:0) after 1m0s
[23:12:18.557] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:162:0)
[23:12:18.557] Timer tick, asking Buildbucket for the build status
[23:12:18.617] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:18.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:163:0) after 1m0s
[23:13:18.647] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:163:0)
[23:13:18.647] Timer tick, asking Buildbucket for the build status
[23:13:18.687] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:18.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:164:0) after 1m0s
[23:14:18.713] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:164:0)
[23:14:18.713] Timer tick, asking Buildbucket for the build status
[23:14:18.778] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:18.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:165:0) after 1m0s
[23:15:18.799] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:165:0)
[23:15:18.799] Timer tick, asking Buildbucket for the build status
[23:15:18.906] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:18.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:166:0) after 1m0s
[23:16:18.924] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:166:0)
[23:16:18.924] Timer tick, asking Buildbucket for the build status
[23:16:18.977] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:18.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:167:0) after 1m0s
[23:17:18.999] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:167:0)
[23:17:18.999] Timer tick, asking Buildbucket for the build status
[23:17:19.047] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:19.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:168:0) after 1m0s
[23:18:19.076] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:168:0)
[23:18:19.076] Timer tick, asking Buildbucket for the build status
[23:18:19.151] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:19.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:169:0) after 1m0s
[23:19:19.186] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:169:0)
[23:19:19.186] Timer tick, asking Buildbucket for the build status
[23:19:19.253] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:19.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:170:0) after 1m0s
[23:20:19.277] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:170:0)
[23:20:19.277] Timer tick, asking Buildbucket for the build status
[23:20:19.327] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:19.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:171:0) after 1m0s
[23:21:19.350] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:171:0)
[23:21:19.350] Timer tick, asking Buildbucket for the build status
[23:21:19.407] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:19.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:172:0) after 1m0s
[23:22:19.466] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:172:0)
[23:22:19.466] Timer tick, asking Buildbucket for the build status
[23:22:19.520] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:19.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:173:0) after 1m0s
[23:23:19.545] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:173:0)
[23:23:19.545] Timer tick, asking Buildbucket for the build status
[23:23:19.603] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:19.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:174:0) after 1m0s
[23:24:19.624] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:174:0)
[23:24:19.624] Timer tick, asking Buildbucket for the build status
[23:24:19.677] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:19.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:175:0) after 1m0s
[23:25:19.761] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:175:0)
[23:25:19.761] Timer tick, asking Buildbucket for the build status
[23:25:19.985] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:19.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:176:0) after 1m0s
[23:26:20.007] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:176:0)
[23:26:20.007] Timer tick, asking Buildbucket for the build status
[23:26:20.072] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:20.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:177:0) after 1m0s
[23:27:20.094] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:177:0)
[23:27:20.094] Timer tick, asking Buildbucket for the build status
[23:27:20.379] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:20.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:178:0) after 1m0s
[23:28:20.463] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:178:0)
[23:28:20.463] Timer tick, asking Buildbucket for the build status
[23:28:20.660] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:20.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:179:0) after 1m0s
[23:29:20.681] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:179:0)
[23:29:20.681] Timer tick, asking Buildbucket for the build status
[23:29:20.730] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:20.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:180:0) after 1m0s
[23:30:20.749] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:180:0)
[23:30:20.749] Timer tick, asking Buildbucket for the build status
[23:30:20.808] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:20.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:181:0) after 1m0s
[23:31:20.837] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:181:0)
[23:31:20.837] Timer tick, asking Buildbucket for the build status
[23:31:20.891] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:20.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:182:0) after 1m0s
[23:32:20.927] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:182:0)
[23:32:20.927] Timer tick, asking Buildbucket for the build status
[23:32:20.988] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:20.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:183:0) after 1m0s
[23:33:21.024] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:183:0)
[23:33:21.024] Timer tick, asking Buildbucket for the build status
[23:33:21.102] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:21.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:184:0) after 1m0s
[23:34:21.122] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:184:0)
[23:34:21.122] Timer tick, asking Buildbucket for the build status
[23:34:21.369] Build 8921516066925378000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:21.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9086946129101801696:185:0) after 1m0s
[23:34:47.351] Received PubSub notification, asking Buildbucket for the build status
[23:34:47.397] Build 8921516066925378000: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:34:47.397] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "AUTO",
    "completed_ts": "1550187286080007",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1550176285775489",
    "failure_reason": "BUILD_FAILURE",
    "id": "8921516066925378000",
    "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\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"0\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-1\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2670\"], \"server_version\": [\"4098-34330fc\"], \"machine_type\": [\"n1-highcpu-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"vm316-m1\"], \"pool\": [\"luci.flex.ci\"], \"caches\": [\"builder_104bd9a76c554f27456c85a5491ddf5f85dcadb4b39968a4576655287a5cf526_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_78893cbd4c292ba203eb7a045a56252df7f14bd0ad3b7b91fac8bfee09f12c2b_v2\", \"builder_7ea55abfea25d314356d803a5f0ad62170c3dfd6004ffb0ffd94b75873d1ce3b_v2\", \"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"builder_fa9d879aabe313024a0ba592a6a7867dc3149ebafcc9bdcf1ac65c59a572870d_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure annotated steps\\nFailure predownload validator corpus\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 102, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"linux-64-validator-opt\", \"key\": \"builder\"}, {\"value\": \"commit/git/15362ac60573769f6606ab1d1c11668052e411a7\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7\", \"key\": \"buildset\"}, {\"value\": \"9086946129101801696\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"nacl/linux-64-validator-opt\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"nacl\", \"builder\": \"linux-64-validator-opt\", \"bucket\": \"ci\"}, \"number\": 102, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"85e01437f26ff37dc358ef303d841313756fe1f8\"}, \"recipe\": {\"name\": \"nacl\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921516066925378000\", \"input\": {\"gitilesCommit\": {\"project\": \"native_client/src/native_client\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"15362ac60573769f6606ab1d1c11668052e411a7\"}}, \"createTime\": \"2019-02-14T20:31:25.775489Z\"}}, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-64-validator-opt\", \"got_revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550176285775489, \"tags\": [\"build_address:luci.nacl.ci/linux-64-validator-opt/102\", \"builder:linux-64-validator-opt\", \"buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086946129101801696\", \"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\": \"8921516066925378000\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"15362ac60573769f6606ab1d1c11668052e411a7\", \"bot_id\": \"vm316-m1\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1550176341292539",
    "status": "COMPLETED",
    "status_changed_ts": "1550187286986450",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/102",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/15362ac60573769f6606ab1d1c11668052e411a7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/15362ac60573769f6606ab1d1c11668052e411a7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9086946129101801696",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8921516066925378000/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:4306867eb4350110",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1550187286987966",
    "url": "https://ci.chromium.org/b/8921516066925378000",
    "utcnow_ts": "1550187287385117"
  }
}
[23:34:47.397] Invocation finished in 3h3m23.080902008s with status FAILED