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

41820cd74f31806536462a20b286defcbe5b199d (5 years ago)
c3051401526bb5e18413d6a62fb4ca46633b3b1b (5 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[20:49:22.215] New invocation is queued and will start shortly
[20:49:23.309] Starting the invocation (attempt 1)
[20:49:23.348] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:49:23.348] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:49:23.348] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9050072033536811056",
  "parameters_json": "{\"builder_name\":\"linux-64-validator-opt\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"41820cd74f31806536462a20b286defcbe5b199d\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\",\"title\":\"41820cd74f31806536462a20b286defcbe5b199d\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\",\"title\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
  "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:9050072033536811056",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
    "gitiles_ref:refs/heads/master"
  ]
}
[20:49:28.810] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585342163697279",
    "id": "8884641971317260752",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585342168250828",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/131",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050072033536811056",
      "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/8884641971317260752/+/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:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585342168250997",
    "url": "https://ci.chromium.org/b/8884641971317260752",
    "utcnow_ts": "1585342168782941"
  }
}
[20:49:28.810] Task URL: https://ci.chromium.org/b/8884641971317260752
[20:49:28.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:2:0) after 1m0s
[20:49:43.328] Received PubSub notification, asking Buildbucket for the build status
[20:49:43.977] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:29.024] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:2:0)
[20:50:29.024] Timer tick, asking Buildbucket for the build status
[20:50:29.328] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:29.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:4:0) after 1m0s
[20:51:29.581] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:4:0)
[20:51:29.581] Timer tick, asking Buildbucket for the build status
[20:51:29.869] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:29.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:5:0) after 1m0s
[20:52:29.936] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:5:0)
[20:52:29.936] Timer tick, asking Buildbucket for the build status
[20:52:30.261] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:30.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:6:0) after 1m0s
[20:53:30.335] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:6:0)
[20:53:30.335] Timer tick, asking Buildbucket for the build status
[20:53:31.347] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:31.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:7:0) after 1m0s
[20:54:31.532] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:7:0)
[20:54:31.532] Timer tick, asking Buildbucket for the build status
[20:54:31.788] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:31.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:8:0) after 1m0s
[20:55:31.829] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:8:0)
[20:55:31.829] Timer tick, asking Buildbucket for the build status
[20:55:31.988] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:31.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:9:0) after 1m0s
[20:56:32.055] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:9:0)
[20:56:32.055] Timer tick, asking Buildbucket for the build status
[20:56:32.204] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:32.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:10:0) after 1m0s
[20:57:32.306] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:10:0)
[20:57:32.306] Timer tick, asking Buildbucket for the build status
[20:57:33.176] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:33.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:11:0) after 1m0s
[20:58:33.330] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:11:0)
[20:58:33.330] Timer tick, asking Buildbucket for the build status
[20:58:33.515] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:33.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:12:0) after 1m0s
[20:59:33.580] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:12:0)
[20:59:33.580] Timer tick, asking Buildbucket for the build status
[20:59:33.775] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:33.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:13:0) after 1m0s
[21:00:33.822] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:13:0)
[21:00:33.822] Timer tick, asking Buildbucket for the build status
[21:00:34.158] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:34.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:14:0) after 1m0s
[21:01:34.221] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:14:0)
[21:01:34.221] Timer tick, asking Buildbucket for the build status
[21:01:34.483] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:34.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:15:0) after 1m0s
[21:02:34.684] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:15:0)
[21:02:34.684] Timer tick, asking Buildbucket for the build status
[21:02:34.865] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:34.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:16:0) after 1m0s
[21:03:34.963] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:16:0)
[21:03:34.963] Timer tick, asking Buildbucket for the build status
[21:03:38.406] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:38.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:17:0) after 1m0s
[21:04:38.425] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:17:0)
[21:04:38.425] Timer tick, asking Buildbucket for the build status
[21:04:38.625] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:38.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:18:0) after 1m0s
[21:05:38.639] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:18:0)
[21:05:38.639] Timer tick, asking Buildbucket for the build status
[21:05:38.778] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:38.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:19:0) after 1m0s
[21:06:38.800] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:19:0)
[21:06:38.800] Timer tick, asking Buildbucket for the build status
[21:06:38.946] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:38.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:20:0) after 1m0s
[21:07:39.077] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:20:0)
[21:07:39.077] Timer tick, asking Buildbucket for the build status
[21:07:39.205] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:39.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:21:0) after 1m0s
[21:08:39.485] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:21:0)
[21:08:39.485] Timer tick, asking Buildbucket for the build status
[21:08:41.935] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:41.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:22:0) after 1m0s
[21:09:42.171] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:22:0)
[21:09:42.171] Timer tick, asking Buildbucket for the build status
[21:09:42.341] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:42.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:23:0) after 1m0s
[21:10:42.382] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:23:0)
[21:10:42.382] Timer tick, asking Buildbucket for the build status
[21:10:42.542] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:42.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:24:0) after 1m0s
[21:11:42.676] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:24:0)
[21:11:42.676] Timer tick, asking Buildbucket for the build status
[21:11:42.781] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:42.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:25:0) after 1m0s
[21:12:42.895] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:25:0)
[21:12:42.896] Timer tick, asking Buildbucket for the build status
[21:12:43.084] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:43.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:26:0) after 1m0s
[21:13:43.162] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:26:0)
[21:13:43.162] Timer tick, asking Buildbucket for the build status
[21:13:43.408] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:43.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:27:0) after 1m0s
[21:14:43.575] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:27:0)
[21:14:43.575] Timer tick, asking Buildbucket for the build status
[21:14:43.738] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:43.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:28:0) after 1m0s
[21:15:43.815] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:28:0)
[21:15:43.815] Timer tick, asking Buildbucket for the build status
[21:15:44.044] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:44.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:29:0) after 1m0s
[21:16:44.067] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:29:0)
[21:16:44.067] Timer tick, asking Buildbucket for the build status
[21:16:44.244] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:44.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:30:0) after 1m0s
[21:17:44.313] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:30:0)
[21:17:44.313] Timer tick, asking Buildbucket for the build status
[21:17:44.641] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:44.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:31:0) after 1m0s
[21:18:44.689] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:31:0)
[21:18:44.689] Timer tick, asking Buildbucket for the build status
[21:18:45.864] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:45.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:32:0) after 1m0s
[21:19:45.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:32:0)
[21:19:45.898] Timer tick, asking Buildbucket for the build status
[21:19:46.066] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:46.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:33:0) after 1m0s
[21:20:46.091] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:33:0)
[21:20:46.091] Timer tick, asking Buildbucket for the build status
[21:20:46.222] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:46.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:34:0) after 1m0s
[21:21:46.277] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:34:0)
[21:21:46.277] Timer tick, asking Buildbucket for the build status
[21:21:46.484] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:46.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:35:0) after 1m0s
[21:22:46.567] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:35:0)
[21:22:46.567] Timer tick, asking Buildbucket for the build status
[21:22:46.722] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:46.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:36:0) after 1m0s
[21:23:46.796] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:36:0)
[21:23:46.796] Timer tick, asking Buildbucket for the build status
[21:23:48.442] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:48.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:37:0) after 1m0s
[21:24:48.464] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:37:0)
[21:24:48.464] Timer tick, asking Buildbucket for the build status
[21:24:48.758] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:48.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:38:0) after 1m0s
[21:25:48.869] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:38:0)
[21:25:48.869] Timer tick, asking Buildbucket for the build status
[21:25:49.056] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:49.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:39:0) after 1m0s
[21:26:49.169] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:39:0)
[21:26:49.169] Timer tick, asking Buildbucket for the build status
[21:26:49.336] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:49.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:40:0) after 1m0s
[21:27:49.377] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:40:0)
[21:27:49.377] Timer tick, asking Buildbucket for the build status
[21:27:49.763] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:49.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:41:0) after 1m0s
[21:28:49.804] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:41:0)
[21:28:49.804] Timer tick, asking Buildbucket for the build status
[21:28:50.334] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:50.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:42:0) after 1m0s
[21:29:50.359] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:42:0)
[21:29:50.359] Timer tick, asking Buildbucket for the build status
[21:29:50.525] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:50.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:43:0) after 1m0s
[21:30:50.527] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:43:0)
[21:30:50.527] Timer tick, asking Buildbucket for the build status
[21:30:50.680] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:50.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:44:0) after 1m0s
[21:31:50.702] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:44:0)
[21:31:50.702] Timer tick, asking Buildbucket for the build status
[21:31:51.243] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:51.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:45:0) after 1m0s
[21:32:51.418] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:45:0)
[21:32:51.418] Timer tick, asking Buildbucket for the build status
[21:32:51.580] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:51.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:46:0) after 1m0s
[21:33:51.714] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:46:0)
[21:33:51.714] Timer tick, asking Buildbucket for the build status
[21:33:55.318] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:55.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:47:0) after 1m0s
[21:34:56.246] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:47:0)
[21:34:56.246] Timer tick, asking Buildbucket for the build status
[21:34:56.457] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:56.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:48:0) after 1m0s
[21:35:56.592] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:48:0)
[21:35:56.592] Timer tick, asking Buildbucket for the build status
[21:35:56.860] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:56.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:49:0) after 1m0s
[21:36:56.951] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:49:0)
[21:36:56.951] Timer tick, asking Buildbucket for the build status
[21:36:57.152] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:57.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:50:0) after 1m0s
[21:37:57.196] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:50:0)
[21:37:57.196] Timer tick, asking Buildbucket for the build status
[21:37:57.564] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:57.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:51:0) after 1m0s
[21:38:57.807] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:51:0)
[21:38:57.807] Timer tick, asking Buildbucket for the build status
[21:38:57.904] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:57.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:52:0) after 1m0s
[21:39:57.975] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:52:0)
[21:39:57.975] Timer tick, asking Buildbucket for the build status
[21:39:58.107] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:58.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:53:0) after 1m0s
[21:40:58.225] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:53:0)
[21:40:58.225] Timer tick, asking Buildbucket for the build status
[21:40:58.345] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:58.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:54:0) after 1m0s
[21:41:58.458] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:54:0)
[21:41:58.458] Timer tick, asking Buildbucket for the build status
[21:41:58.657] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:58.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:55:0) after 1m0s
[21:42:58.744] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:55:0)
[21:42:58.744] Timer tick, asking Buildbucket for the build status
[21:42:59.038] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:59.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:56:0) after 1m0s
[21:43:59.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:56:0)
[21:43:59.384] Timer tick, asking Buildbucket for the build status
[21:43:59.614] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:59.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:57:0) after 1m0s
[21:44:59.989] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:57:0)
[21:44:59.989] Timer tick, asking Buildbucket for the build status
[21:45:00.344] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:00.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:58:0) after 1m0s
[21:46:00.354] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:58:0)
[21:46:00.354] Timer tick, asking Buildbucket for the build status
[21:46:00.536] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:00.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:59:0) after 1m0s
[21:47:00.595] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:59:0)
[21:47:00.595] Timer tick, asking Buildbucket for the build status
[21:47:00.758] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:00.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:60:0) after 1m0s
[21:48:00.847] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:60:0)
[21:48:00.847] Timer tick, asking Buildbucket for the build status
[21:48:01.221] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:01.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:61:0) after 1m0s
[21:49:01.375] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:61:0)
[21:49:01.375] Timer tick, asking Buildbucket for the build status
[21:49:01.767] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:01.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:62:0) after 1m0s
[21:50:01.840] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:62:0)
[21:50:01.840] Timer tick, asking Buildbucket for the build status
[21:50:02.112] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:02.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:63:0) after 1m0s
[21:51:02.215] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:63:0)
[21:51:02.215] Timer tick, asking Buildbucket for the build status
[21:51:02.549] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:02.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:64:0) after 1m0s
[21:52:02.664] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:64:0)
[21:52:02.664] Timer tick, asking Buildbucket for the build status
[21:52:02.931] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:02.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:65:0) after 1m0s
[21:53:03.006] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:65:0)
[21:53:03.006] Timer tick, asking Buildbucket for the build status
[21:53:03.386] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:03.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:66:0) after 1m0s
[21:54:03.448] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:66:0)
[21:54:03.448] Timer tick, asking Buildbucket for the build status
[21:54:03.876] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:03.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:67:0) after 1m0s
[21:55:03.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:67:0)
[21:55:03.907] Timer tick, asking Buildbucket for the build status
[21:55:04.051] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:04.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:68:0) after 1m0s
[21:56:04.076] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:68:0)
[21:56:04.076] Timer tick, asking Buildbucket for the build status
[21:56:04.338] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:04.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:69:0) after 1m0s
[21:57:04.367] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:69:0)
[21:57:04.367] Timer tick, asking Buildbucket for the build status
[21:57:04.782] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:04.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:70:0) after 1m0s
[21:58:04.813] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:70:0)
[21:58:04.813] Timer tick, asking Buildbucket for the build status
[21:58:05.638] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:05.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:71:0) after 1m0s
[21:59:05.694] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:71:0)
[21:59:05.694] Timer tick, asking Buildbucket for the build status
[21:59:06.186] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:06.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:72:0) after 1m0s
[22:00:06.341] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:72:0)
[22:00:06.341] Timer tick, asking Buildbucket for the build status
[22:00:06.753] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:06.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:73:0) after 1m0s
[22:01:06.773] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:73:0)
[22:01:06.773] Timer tick, asking Buildbucket for the build status
[22:01:07.474] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:07.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:74:0) after 1m0s
[22:02:07.583] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:74:0)
[22:02:07.583] Timer tick, asking Buildbucket for the build status
[22:02:07.774] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:07.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:75:0) after 1m0s
[22:03:07.871] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:75:0)
[22:03:07.871] Timer tick, asking Buildbucket for the build status
[22:03:08.034] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:08.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:76:0) after 1m0s
[22:04:08.206] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:76:0)
[22:04:08.206] Timer tick, asking Buildbucket for the build status
[22:04:08.400] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:08.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:77:0) after 1m0s
[22:05:08.453] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:77:0)
[22:05:08.453] Timer tick, asking Buildbucket for the build status
[22:05:08.561] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:08.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:78:0) after 1m0s
[22:06:08.716] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:78:0)
[22:06:08.716] Timer tick, asking Buildbucket for the build status
[22:06:09.080] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:09.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:79:0) after 1m0s
[22:07:09.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:79:0)
[22:07:09.153] Timer tick, asking Buildbucket for the build status
[22:07:09.507] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:09.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:80:0) after 1m0s
[22:08:09.722] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:80:0)
[22:08:09.722] Timer tick, asking Buildbucket for the build status
[22:08:09.926] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:09.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:81:0) after 1m0s
[22:09:10.087] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:81:0)
[22:09:10.087] Timer tick, asking Buildbucket for the build status
[22:09:10.298] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:10.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:82:0) after 1m0s
[22:10:10.409] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:82:0)
[22:10:10.409] Timer tick, asking Buildbucket for the build status
[22:10:10.591] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:10.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:83:0) after 1m0s
[22:11:10.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:83:0)
[22:11:10.640] Timer tick, asking Buildbucket for the build status
[22:11:10.941] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:10.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:84:0) after 1m0s
[22:12:11.071] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:84:0)
[22:12:11.071] Timer tick, asking Buildbucket for the build status
[22:12:11.195] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:11.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:85:0) after 1m0s
[22:13:11.260] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:85:0)
[22:13:11.260] Timer tick, asking Buildbucket for the build status
[22:13:11.648] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:11.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:86:0) after 1m0s
[22:14:11.707] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:86:0)
[22:14:11.707] Timer tick, asking Buildbucket for the build status
[22:14:11.963] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:11.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:87:0) after 1m0s
[22:15:12.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:87:0)
[22:15:12.153] Timer tick, asking Buildbucket for the build status
[22:15:12.396] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:12.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:88:0) after 1m0s
[22:16:12.471] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:88:0)
[22:16:12.471] Timer tick, asking Buildbucket for the build status
[22:16:12.607] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:12.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:89:0) after 1m0s
[22:17:12.631] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:89:0)
[22:17:12.631] Timer tick, asking Buildbucket for the build status
[22:17:13.003] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:13.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:90:0) after 1m0s
[22:18:13.055] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:90:0)
[22:18:13.055] Timer tick, asking Buildbucket for the build status
[22:18:13.262] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:13.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:91:0) after 1m0s
[22:19:13.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:91:0)
[22:19:13.281] Timer tick, asking Buildbucket for the build status
[22:19:13.463] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:13.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:92:0) after 1m0s
[22:20:13.535] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:92:0)
[22:20:13.535] Timer tick, asking Buildbucket for the build status
[22:20:13.705] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:13.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:93:0) after 1m0s
[22:21:13.725] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:93:0)
[22:21:13.725] Timer tick, asking Buildbucket for the build status
[22:21:14.146] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:14.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:94:0) after 1m0s
[22:22:14.180] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:94:0)
[22:22:14.180] Timer tick, asking Buildbucket for the build status
[22:22:14.589] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:14.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:95:0) after 1m0s
[22:23:14.619] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:95:0)
[22:23:14.619] Timer tick, asking Buildbucket for the build status
[22:23:14.748] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:14.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:96:0) after 1m0s
[22:24:14.771] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:96:0)
[22:24:14.771] Timer tick, asking Buildbucket for the build status
[22:24:14.893] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:14.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:97:0) after 1m0s
[22:25:14.934] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:97:0)
[22:25:14.934] Timer tick, asking Buildbucket for the build status
[22:25:15.230] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:15.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:98:0) after 1m0s
[22:26:15.273] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:98:0)
[22:26:15.273] Timer tick, asking Buildbucket for the build status
[22:26:15.482] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:15.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:99:0) after 1m0s
[22:27:15.505] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:99:0)
[22:27:15.505] Timer tick, asking Buildbucket for the build status
[22:27:15.688] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:15.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:100:0) after 1m0s
[22:28:15.713] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:100:0)
[22:28:15.713] Timer tick, asking Buildbucket for the build status
[22:28:16.486] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:16.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:101:0) after 1m0s
[22:29:16.528] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:101:0)
[22:29:16.528] Timer tick, asking Buildbucket for the build status
[22:29:16.745] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:16.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:102:0) after 1m0s
[22:30:16.822] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:102:0)
[22:30:16.822] Timer tick, asking Buildbucket for the build status
[22:30:17.046] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:17.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:103:0) after 1m0s
[22:31:17.147] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:103:0)
[22:31:17.147] Timer tick, asking Buildbucket for the build status
[22:31:17.325] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:17.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:104:0) after 1m0s
[22:32:17.416] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:104:0)
[22:32:17.416] Timer tick, asking Buildbucket for the build status
[22:32:17.716] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:17.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:105:0) after 1m0s
[22:33:17.707] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:105:0)
[22:33:17.707] Timer tick, asking Buildbucket for the build status
[22:33:17.890] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:17.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:106:0) after 1m0s
[22:34:17.995] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:106:0)
[22:34:17.995] Timer tick, asking Buildbucket for the build status
[22:34:18.116] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:18.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:107:0) after 1m0s
[22:35:18.296] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:107:0)
[22:35:18.296] Timer tick, asking Buildbucket for the build status
[22:35:18.477] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:18.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:108:0) after 1m0s
[22:36:18.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:108:0)
[22:36:18.606] Timer tick, asking Buildbucket for the build status
[22:36:19.158] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:19.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:109:0) after 1m0s
[22:37:19.159] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:109:0)
[22:37:19.159] Timer tick, asking Buildbucket for the build status
[22:37:19.389] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:19.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:110:0) after 1m0s
[22:38:19.410] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:110:0)
[22:38:19.410] Timer tick, asking Buildbucket for the build status
[22:38:20.112] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:20.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:111:0) after 1m0s
[22:39:20.186] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:111:0)
[22:39:20.186] Timer tick, asking Buildbucket for the build status
[22:39:20.401] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:20.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:112:0) after 1m0s
[22:40:20.679] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:112:0)
[22:40:20.679] Timer tick, asking Buildbucket for the build status
[22:40:20.873] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:20.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:113:0) after 1m0s
[22:41:20.894] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:113:0)
[22:41:20.894] Timer tick, asking Buildbucket for the build status
[22:41:21.028] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:21.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:114:0) after 1m0s
[22:42:21.065] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:114:0)
[22:42:21.065] Timer tick, asking Buildbucket for the build status
[22:42:21.205] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:21.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:115:0) after 1m0s
[22:43:21.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:115:0)
[22:43:21.242] Timer tick, asking Buildbucket for the build status
[22:43:21.530] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:21.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:116:0) after 1m0s
[22:44:21.557] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:116:0)
[22:44:21.557] Timer tick, asking Buildbucket for the build status
[22:44:21.738] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:21.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:117:0) after 1m0s
[22:45:21.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:117:0)
[22:45:21.854] Timer tick, asking Buildbucket for the build status
[22:45:21.958] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:21.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:118:0) after 1m0s
[22:46:21.983] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:118:0)
[22:46:21.983] Timer tick, asking Buildbucket for the build status
[22:46:22.189] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:22.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:119:0) after 1m0s
[22:47:22.214] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:119:0)
[22:47:22.214] Timer tick, asking Buildbucket for the build status
[22:47:22.313] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:22.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:120:0) after 1m0s
[22:48:22.402] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:120:0)
[22:48:22.402] Timer tick, asking Buildbucket for the build status
[22:48:22.662] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:22.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:121:0) after 1m0s
[22:49:22.798] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:121:0)
[22:49:22.798] Timer tick, asking Buildbucket for the build status
[22:49:22.932] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:22.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:122:0) after 1m0s
[22:50:22.969] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:122:0)
[22:50:22.969] Timer tick, asking Buildbucket for the build status
[22:50:23.226] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:23.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:123:0) after 1m0s
[22:51:23.287] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:123:0)
[22:51:23.287] Timer tick, asking Buildbucket for the build status
[22:51:23.368] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:23.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:124:0) after 1m0s
[22:52:23.427] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:124:0)
[22:52:23.427] Timer tick, asking Buildbucket for the build status
[22:52:23.648] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:23.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:125:0) after 1m0s
[22:53:23.787] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:125:0)
[22:53:23.787] Timer tick, asking Buildbucket for the build status
[22:53:27.232] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:27.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:126:0) after 1m0s
[22:54:27.299] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:126:0)
[22:54:27.299] Timer tick, asking Buildbucket for the build status
[22:54:27.383] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:27.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:127:0) after 1m0s
[22:55:27.440] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:127:0)
[22:55:27.440] Timer tick, asking Buildbucket for the build status
[22:55:27.798] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:27.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:128:0) after 1m0s
[22:56:27.794] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:128:0)
[22:56:27.794] Timer tick, asking Buildbucket for the build status
[22:56:27.974] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:27.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:129:0) after 1m0s
[22:57:28.012] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:129:0)
[22:57:28.012] Timer tick, asking Buildbucket for the build status
[22:57:28.185] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:28.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:130:0) after 1m0s
[22:58:28.199] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:130:0)
[22:58:28.199] Timer tick, asking Buildbucket for the build status
[22:58:28.866] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:28.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:131:0) after 1m0s
[22:59:29.049] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:131:0)
[22:59:29.049] Timer tick, asking Buildbucket for the build status
[22:59:29.647] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:29.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:132:0) after 1m0s
[23:00:29.676] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:132:0)
[23:00:29.676] Timer tick, asking Buildbucket for the build status
[23:00:30.321] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:30.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:133:0) after 1m0s
[23:01:30.368] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:133:0)
[23:01:30.368] Timer tick, asking Buildbucket for the build status
[23:01:30.489] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:30.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:134:0) after 1m0s
[23:02:30.766] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:134:0)
[23:02:30.766] Timer tick, asking Buildbucket for the build status
[23:02:30.871] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:30.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:135:0) after 1m0s
[23:03:30.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:135:0)
[23:03:30.898] Timer tick, asking Buildbucket for the build status
[23:03:37.313] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:37.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:136:0) after 1m0s
[23:04:37.498] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:136:0)
[23:04:37.498] Timer tick, asking Buildbucket for the build status
[23:04:38.742] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:38.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:137:0) after 1m0s
[23:05:38.857] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:137:0)
[23:05:38.857] Timer tick, asking Buildbucket for the build status
[23:05:39.052] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:39.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:138:0) after 1m0s
[23:06:39.092] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:138:0)
[23:06:39.092] Timer tick, asking Buildbucket for the build status
[23:06:39.290] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:39.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:139:0) after 1m0s
[23:07:39.393] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:139:0)
[23:07:39.393] Timer tick, asking Buildbucket for the build status
[23:07:39.604] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:39.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:140:0) after 1m0s
[23:08:39.697] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:140:0)
[23:08:39.697] Timer tick, asking Buildbucket for the build status
[23:08:43.002] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:43.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:141:0) after 1m0s
[23:09:43.166] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:141:0)
[23:09:43.166] Timer tick, asking Buildbucket for the build status
[23:09:43.296] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:43.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:142:0) after 1m0s
[23:10:43.323] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:142:0)
[23:10:43.323] Timer tick, asking Buildbucket for the build status
[23:10:43.572] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:43.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:143:0) after 1m0s
[23:11:43.687] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:143:0)
[23:11:43.687] Timer tick, asking Buildbucket for the build status
[23:11:43.866] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:43.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:144:0) after 1m0s
[23:12:43.892] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:144:0)
[23:12:43.892] Timer tick, asking Buildbucket for the build status
[23:12:44.292] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:44.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:145:0) after 1m0s
[23:13:44.423] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:145:0)
[23:13:44.423] Timer tick, asking Buildbucket for the build status
[23:13:44.738] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:44.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:146:0) after 1m0s
[23:14:44.833] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:146:0)
[23:14:44.833] Timer tick, asking Buildbucket for the build status
[23:14:45.701] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:45.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:147:0) after 1m0s
[23:15:45.711] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:147:0)
[23:15:45.711] Timer tick, asking Buildbucket for the build status
[23:15:45.807] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:45.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:148:0) after 1m0s
[23:16:45.861] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:148:0)
[23:16:45.861] Timer tick, asking Buildbucket for the build status
[23:16:46.448] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:46.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:149:0) after 1m0s
[23:17:46.477] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:149:0)
[23:17:46.477] Timer tick, asking Buildbucket for the build status
[23:17:46.593] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:46.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:150:0) after 1m0s
[23:18:46.740] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:150:0)
[23:18:46.740] Timer tick, asking Buildbucket for the build status
[23:18:47.073] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:47.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:151:0) after 1m0s
[23:19:47.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:151:0)
[23:19:47.157] Timer tick, asking Buildbucket for the build status
[23:19:47.494] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:47.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:152:0) after 1m0s
[23:20:47.481] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:152:0)
[23:20:47.481] Timer tick, asking Buildbucket for the build status
[23:20:47.910] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:47.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:153:0) after 1m0s
[23:21:48.164] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:153:0)
[23:21:48.164] Timer tick, asking Buildbucket for the build status
[23:21:48.329] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:48.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:154:0) after 1m0s
[23:22:48.363] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:154:0)
[23:22:48.363] Timer tick, asking Buildbucket for the build status
[23:22:48.568] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:48.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:155:0) after 1m0s
[23:23:48.728] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:155:0)
[23:23:48.728] Timer tick, asking Buildbucket for the build status
[23:23:48.919] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:48.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:156:0) after 1m0s
[23:24:48.990] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:156:0)
[23:24:48.990] Timer tick, asking Buildbucket for the build status
[23:24:49.272] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:49.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:157:0) after 1m0s
[23:25:49.372] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:157:0)
[23:25:49.372] Timer tick, asking Buildbucket for the build status
[23:25:49.512] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:49.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:158:0) after 1m0s
[23:26:49.564] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:158:0)
[23:26:49.564] Timer tick, asking Buildbucket for the build status
[23:26:49.901] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:49.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:159:0) after 1m0s
[23:27:49.962] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:159:0)
[23:27:49.962] Timer tick, asking Buildbucket for the build status
[23:27:50.517] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:50.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:160:0) after 1m0s
[23:28:50.586] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:160:0)
[23:28:50.586] Timer tick, asking Buildbucket for the build status
[23:28:50.721] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:50.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:161:0) after 1m0s
[23:29:50.743] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:161:0)
[23:29:50.743] Timer tick, asking Buildbucket for the build status
[23:29:50.879] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:50.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:162:0) after 1m0s
[23:30:50.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:162:0)
[23:30:50.981] Timer tick, asking Buildbucket for the build status
[23:30:51.162] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:51.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:163:0) after 1m0s
[23:31:51.279] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:163:0)
[23:31:51.279] Timer tick, asking Buildbucket for the build status
[23:31:51.785] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:51.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:164:0) after 1m0s
[23:32:52.032] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:164:0)
[23:32:52.032] Timer tick, asking Buildbucket for the build status
[23:32:52.197] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:52.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:165:0) after 1m0s
[23:33:52.401] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:165:0)
[23:33:52.401] Timer tick, asking Buildbucket for the build status
[23:33:52.540] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:52.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:166:0) after 1m0s
[23:34:52.626] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:166:0)
[23:34:52.626] Timer tick, asking Buildbucket for the build status
[23:34:52.971] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:52.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:167:0) after 1m0s
[23:35:53.217] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:167:0)
[23:35:53.217] Timer tick, asking Buildbucket for the build status
[23:35:53.484] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:53.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:168:0) after 1m0s
[23:36:53.613] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:168:0)
[23:36:53.613] Timer tick, asking Buildbucket for the build status
[23:36:53.938] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:53.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:169:0) after 1m0s
[23:38:00.111] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:169:0)
[23:38:00.111] Timer tick, asking Buildbucket for the build status
[23:38:00.587] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:00.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:170:0) after 1m0s
[23:39:01.118] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:170:0)
[23:39:01.118] Timer tick, asking Buildbucket for the build status
[23:39:01.327] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:01.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:171:0) after 1m0s
[23:40:01.428] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:171:0)
[23:40:01.428] Timer tick, asking Buildbucket for the build status
[23:40:01.626] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:01.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:172:0) after 1m0s
[23:41:01.821] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:172:0)
[23:41:01.821] Timer tick, asking Buildbucket for the build status
[23:41:02.065] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:02.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:173:0) after 1m0s
[23:42:02.129] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:173:0)
[23:42:02.129] Timer tick, asking Buildbucket for the build status
[23:42:02.284] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:02.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:174:0) after 1m0s
[23:43:02.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:174:0)
[23:43:02.385] Timer tick, asking Buildbucket for the build status
[23:43:02.753] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:02.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:175:0) after 1m0s
[23:44:02.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:175:0)
[23:44:02.907] Timer tick, asking Buildbucket for the build status
[23:44:03.082] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:03.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:176:0) after 1m0s
[23:45:03.393] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:176:0)
[23:45:03.393] Timer tick, asking Buildbucket for the build status
[23:45:03.818] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:03.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:177:0) after 1m0s
[23:46:03.874] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:177:0)
[23:46:03.874] Timer tick, asking Buildbucket for the build status
[23:46:05.339] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:05.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:178:0) after 1m0s
[23:47:05.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:178:0)
[23:47:05.384] Timer tick, asking Buildbucket for the build status
[23:47:05.559] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:179:0) after 1m0s
[23:48:05.597] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:179:0)
[23:48:05.597] Timer tick, asking Buildbucket for the build status
[23:48:05.808] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:05.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:180:0) after 1m0s
[23:49:05.929] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:180:0)
[23:49:05.929] Timer tick, asking Buildbucket for the build status
[23:49:06.217] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:181:0) after 1m0s
[23:50:06.326] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:181:0)
[23:50:06.326] Timer tick, asking Buildbucket for the build status
[23:50:06.979] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:182:0) after 1m0s
[23:51:06.998] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:182:0)
[23:51:06.998] Timer tick, asking Buildbucket for the build status
[23:51:07.175] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:07.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:183:0) after 1m0s
[23:52:07.307] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:183:0)
[23:52:07.307] Timer tick, asking Buildbucket for the build status
[23:52:07.482] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:07.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:184:0) after 1m0s
[23:53:07.568] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:184:0)
[23:53:07.568] Timer tick, asking Buildbucket for the build status
[23:53:07.726] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:07.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:185:0) after 1m0s
[23:54:07.816] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:185:0)
[23:54:07.816] Timer tick, asking Buildbucket for the build status
[23:54:09.345] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:09.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:186:0) after 1m0s
[23:55:09.390] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:186:0)
[23:55:09.390] Timer tick, asking Buildbucket for the build status
[23:55:09.690] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:09.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:187:0) after 1m0s
[23:56:09.916] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:187:0)
[23:56:09.916] Timer tick, asking Buildbucket for the build status
[23:56:10.137] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:10.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:188:0) after 1m0s
[23:57:10.189] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:188:0)
[23:57:10.189] Timer tick, asking Buildbucket for the build status
[23:57:10.482] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:10.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:189:0) after 1m0s
[23:58:10.574] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:189:0)
[23:58:10.574] Timer tick, asking Buildbucket for the build status
[23:58:10.737] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:10.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:190:0) after 1m0s
[23:59:10.868] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:190:0)
[23:59:10.868] Timer tick, asking Buildbucket for the build status
[23:59:11.024] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:11.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:191:0) after 1m0s
[00:00:11.265] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:191:0)
[00:00:11.265] Timer tick, asking Buildbucket for the build status
[00:00:11.823] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:11.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:192:0) after 1m0s
[00:01:12.074] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:192:0)
[00:01:12.074] Timer tick, asking Buildbucket for the build status
[00:01:12.354] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:12.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:193:0) after 1m0s
[00:02:12.527] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:193:0)
[00:02:12.527] Timer tick, asking Buildbucket for the build status
[00:02:12.722] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:12.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:194:0) after 1m0s
[00:03:12.887] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:194:0)
[00:03:12.887] Timer tick, asking Buildbucket for the build status
[00:03:13.048] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:13.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:195:0) after 1m0s
[00:04:13.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:195:0)
[00:04:13.325] Timer tick, asking Buildbucket for the build status
[00:04:13.489] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:13.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:196:0) after 1m0s
[00:05:13.509] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:196:0)
[00:05:13.509] Timer tick, asking Buildbucket for the build status
[00:05:13.786] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:13.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:197:0) after 1m0s
[00:06:13.865] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:197:0)
[00:06:13.865] Timer tick, asking Buildbucket for the build status
[00:06:14.221] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:14.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:198:0) after 1m0s
[00:07:14.336] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:198:0)
[00:07:14.336] Timer tick, asking Buildbucket for the build status
[00:07:14.996] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:14.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:199:0) after 1m0s
[00:08:15.218] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:199:0)
[00:08:15.218] Timer tick, asking Buildbucket for the build status
[00:08:17.338] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:17.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:200:0) after 1m0s
[00:09:17.332] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:200:0)
[00:09:17.332] Timer tick, asking Buildbucket for the build status
[00:09:17.485] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:17.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:201:0) after 1m0s
[00:10:17.462] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:201:0)
[00:10:17.462] Timer tick, asking Buildbucket for the build status
[00:10:18.188] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:18.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:202:0) after 1m0s
[00:11:18.262] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:202:0)
[00:11:18.262] Timer tick, asking Buildbucket for the build status
[00:11:18.500] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:18.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:203:0) after 1m0s
[00:12:18.607] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:203:0)
[00:12:18.607] Timer tick, asking Buildbucket for the build status
[00:12:18.731] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:18.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:204:0) after 1m0s
[00:13:18.717] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:204:0)
[00:13:18.717] Timer tick, asking Buildbucket for the build status
[00:13:19.446] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:19.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:205:0) after 1m0s
[00:14:19.618] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:205:0)
[00:14:19.618] Timer tick, asking Buildbucket for the build status
[00:14:19.807] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:19.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:206:0) after 1m0s
[00:15:19.885] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:206:0)
[00:15:19.885] Timer tick, asking Buildbucket for the build status
[00:15:20.302] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:20.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:207:0) after 1m0s
[00:16:20.384] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:207:0)
[00:16:20.384] Timer tick, asking Buildbucket for the build status
[00:16:20.818] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:20.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:208:0) after 1m0s
[00:17:20.928] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:208:0)
[00:17:20.928] Timer tick, asking Buildbucket for the build status
[00:17:21.142] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:21.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:209:0) after 1m0s
[00:18:21.197] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:209:0)
[00:18:21.197] Timer tick, asking Buildbucket for the build status
[00:18:21.576] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:21.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:210:0) after 1m0s
[00:19:21.634] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:210:0)
[00:19:21.634] Timer tick, asking Buildbucket for the build status
[00:19:21.893] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:21.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:211:0) after 1m0s
[00:20:21.983] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:211:0)
[00:20:21.983] Timer tick, asking Buildbucket for the build status
[00:20:22.152] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:22.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:212:0) after 1m0s
[00:21:22.179] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:212:0)
[00:21:22.179] Timer tick, asking Buildbucket for the build status
[00:21:22.338] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:22.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:213:0) after 1m0s
[00:22:22.485] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:213:0)
[00:22:22.485] Timer tick, asking Buildbucket for the build status
[00:22:22.664] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:22.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:214:0) after 1m0s
[00:23:22.761] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:214:0)
[00:23:22.761] Timer tick, asking Buildbucket for the build status
[00:23:24.879] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:24.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:215:0) after 1m0s
[00:24:24.916] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:215:0)
[00:24:24.916] Timer tick, asking Buildbucket for the build status
[00:24:25.254] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:25.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:216:0) after 1m0s
[00:25:25.325] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:216:0)
[00:25:25.325] Timer tick, asking Buildbucket for the build status
[00:25:25.696] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:25.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:217:0) after 1m0s
[00:26:25.792] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:217:0)
[00:26:25.792] Timer tick, asking Buildbucket for the build status
[00:26:26.465] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:26.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:218:0) after 1m0s
[00:27:26.636] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:218:0)
[00:27:26.636] Timer tick, asking Buildbucket for the build status
[00:27:26.907] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:26.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:219:0) after 1m0s
[00:28:26.972] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:219:0)
[00:28:26.972] Timer tick, asking Buildbucket for the build status
[00:28:27.866] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:27.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:220:0) after 1m0s
[00:29:27.898] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:220:0)
[00:29:27.898] Timer tick, asking Buildbucket for the build status
[00:29:28.095] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:28.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:221:0) after 1m0s
[00:30:28.140] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:221:0)
[00:30:28.140] Timer tick, asking Buildbucket for the build status
[00:30:28.607] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:28.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:222:0) after 1m0s
[00:31:28.663] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:222:0)
[00:31:28.663] Timer tick, asking Buildbucket for the build status
[00:31:28.926] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:28.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:223:0) after 1m0s
[00:32:28.952] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:223:0)
[00:32:28.952] Timer tick, asking Buildbucket for the build status
[00:32:29.158] Build 8884641971317260752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:29.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050072033536811056:224:0) after 1m0s
[00:33:25.328] Received PubSub notification, asking Buildbucket for the build status
[00:33:25.688] Build 8884641971317260752: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:33:25.688] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "completed_ts": "1585355604675754",
    "created_by": "project:nacl",
    "created_ts": "1585342163697279",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884641971317260752",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"bot_id\": \"luci-flex-ci-xenial-3-rmrm\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-64-validator-opt\", \"buildnumber\": 131, \"got_revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"mastername\": \"client.nacl\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_41bff59e48143683eee7e209c35176da7d4c8f0bf6fc4cc531114b3142934c50_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_78893cbd4c292ba203eb7a045a56252df7f14bd0ad3b7b91fac8bfee09f12c2b_v2\", \"builder_7ea55abfea25d314356d803a5f0ad62170c3dfd6004ffb0ffd94b75873d1ce3b_v2\", \"builder_faaedc22e3ac6fec098652e7f0fd10d4f879f8f9ad55ea8ef979585009ee36da_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-flex-ci-xenial-3-rmrm\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4901-91f024a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585342182376922",
    "status": "COMPLETED",
    "status_changed_ts": "1585342183012965",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/131",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050072033536811056",
      "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/8884641971317260752/+/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:4b36935b88450110",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585355604772937",
    "url": "https://ci.chromium.org/b/8884641971317260752",
    "utcnow_ts": "1585355605671995"
  }
}
[00:33:25.688] Invocation finished in 3h44m3.490893935s with status FAILED