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

• ebf11aaad97fde7f91b1c72e0dddaf1653d41f77 (5 years ago)

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

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

[00:33:27.411] New invocation is queued and will start shortly
[00:33:29.117] Starting the invocation (attempt 1)
[00:33:29.162] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:33:29.162] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:33:29.162] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9050057935237886560",
  "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\":\"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\",\"title\":\"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}}",
  "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:9050057935237886560",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
    "gitiles_ref:refs/heads/master"
  ]
}
[00:33:33.377] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585355610209064",
    "id": "8884627871627805408",
    "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\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"title\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585355612263875",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/132",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050057935237886560",
      "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/8884627871627805408/+/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": "1585355612264210",
    "url": "https://ci.chromium.org/b/8884627871627805408",
    "utcnow_ts": "1585355613344788"
  }
}
[00:33:33.377] Task URL: https://ci.chromium.org/b/8884627871627805408
[00:33:33.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:2:0) after 1m0s
[00:33:48.097] Received PubSub notification, asking Buildbucket for the build status
[00:33:48.214] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:33.461] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:2:0)
[00:34:33.461] Timer tick, asking Buildbucket for the build status
[00:34:33.647] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:33.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:4:0) after 1m0s
[00:35:33.730] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:4:0)
[00:35:33.730] Timer tick, asking Buildbucket for the build status
[00:35:33.896] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:33.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:5:0) after 1m0s
[00:36:33.915] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:5:0)
[00:36:33.915] Timer tick, asking Buildbucket for the build status
[00:36:34.363] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:34.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:6:0) after 1m0s
[00:37:34.440] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:6:0)
[00:37:34.440] Timer tick, asking Buildbucket for the build status
[00:37:34.735] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:34.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:7:0) after 1m0s
[00:38:34.755] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:7:0)
[00:38:34.755] Timer tick, asking Buildbucket for the build status
[00:38:34.900] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:34.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:8:0) after 1m0s
[00:39:35.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:8:0)
[00:39:35.029] Timer tick, asking Buildbucket for the build status
[00:39:35.409] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:35.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:9:0) after 1m0s
[00:40:35.435] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:9:0)
[00:40:35.435] Timer tick, asking Buildbucket for the build status
[00:40:35.553] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:35.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:10:0) after 1m0s
[00:41:35.601] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:10:0)
[00:41:35.601] Timer tick, asking Buildbucket for the build status
[00:41:35.997] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:35.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:11:0) after 1m0s
[00:42:36.019] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:11:0)
[00:42:36.019] Timer tick, asking Buildbucket for the build status
[00:42:36.258] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:36.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:12:0) after 1m0s
[00:43:36.317] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:12:0)
[00:43:36.317] Timer tick, asking Buildbucket for the build status
[00:43:39.942] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:39.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:13:0) after 1m0s
[00:44:40.003] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:13:0)
[00:44:40.003] Timer tick, asking Buildbucket for the build status
[00:44:40.330] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:14:0) after 1m0s
[00:45:40.408] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:14:0)
[00:45:40.408] Timer tick, asking Buildbucket for the build status
[00:45:40.555] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:15:0) after 1m0s
[00:46:40.684] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:15:0)
[00:46:40.684] Timer tick, asking Buildbucket for the build status
[00:46:40.913] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:40.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:16:0) after 1m0s
[00:47:40.969] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:16:0)
[00:47:40.969] Timer tick, asking Buildbucket for the build status
[00:47:41.103] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:41.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:17:0) after 1m0s
[00:48:41.153] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:17:0)
[00:48:41.153] Timer tick, asking Buildbucket for the build status
[00:48:42.297] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:42.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:18:0) after 1m0s
[00:49:42.379] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:18:0)
[00:49:42.379] Timer tick, asking Buildbucket for the build status
[00:49:42.568] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:42.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:19:0) after 1m0s
[00:50:42.600] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:19:0)
[00:50:42.600] Timer tick, asking Buildbucket for the build status
[00:50:43.542] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:43.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:20:0) after 1m0s
[00:51:43.672] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:20:0)
[00:51:43.672] Timer tick, asking Buildbucket for the build status
[00:51:43.879] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:43.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:21:0) after 1m0s
[00:52:44.009] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:21:0)
[00:52:44.009] Timer tick, asking Buildbucket for the build status
[00:52:44.335] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:44.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:22:0) after 1m0s
[00:53:44.367] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:22:0)
[00:53:44.368] Timer tick, asking Buildbucket for the build status
[00:53:47.819] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:47.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:23:0) after 1m0s
[00:54:47.842] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:23:0)
[00:54:47.842] Timer tick, asking Buildbucket for the build status
[00:54:48.827] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:48.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:24:0) after 1m0s
[00:55:48.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:24:0)
[00:55:48.854] Timer tick, asking Buildbucket for the build status
[00:55:48.994] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:48.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:25:0) after 1m0s
[00:56:49.106] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:25:0)
[00:56:49.106] Timer tick, asking Buildbucket for the build status
[00:56:49.374] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:49.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:26:0) after 1m0s
[00:57:49.421] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:26:0)
[00:57:49.421] Timer tick, asking Buildbucket for the build status
[00:57:49.643] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:49.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:27:0) after 1m0s
[00:58:49.663] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:27:0)
[00:58:49.663] Timer tick, asking Buildbucket for the build status
[00:58:49.943] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:49.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:28:0) after 1m0s
[00:59:50.034] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:28:0)
[00:59:50.034] Timer tick, asking Buildbucket for the build status
[00:59:50.293] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:50.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:29:0) after 1m0s
[01:00:50.311] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:29:0)
[01:00:50.311] Timer tick, asking Buildbucket for the build status
[01:00:51.072] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:51.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:30:0) after 1m0s
[01:01:51.133] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:30:0)
[01:01:51.133] Timer tick, asking Buildbucket for the build status
[01:01:51.668] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:51.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:31:0) after 1m0s
[01:02:51.833] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:31:0)
[01:02:51.833] Timer tick, asking Buildbucket for the build status
[01:02:51.979] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:51.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:32:0) after 1m0s
[01:03:52.115] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:32:0)
[01:03:52.115] Timer tick, asking Buildbucket for the build status
[01:03:52.870] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:52.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:33:0) after 1m0s
[01:04:53.030] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:33:0)
[01:04:53.030] Timer tick, asking Buildbucket for the build status
[01:04:53.305] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:53.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:34:0) after 1m0s
[01:05:53.379] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:34:0)
[01:05:53.379] Timer tick, asking Buildbucket for the build status
[01:05:53.598] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:53.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:35:0) after 1m0s
[01:06:53.631] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:35:0)
[01:06:53.631] Timer tick, asking Buildbucket for the build status
[01:06:53.815] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:53.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:36:0) after 1m0s
[01:07:53.897] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:36:0)
[01:07:53.897] Timer tick, asking Buildbucket for the build status
[01:07:54.336] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:54.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:37:0) after 1m0s
[01:08:54.360] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:37:0)
[01:08:54.360] Timer tick, asking Buildbucket for the build status
[01:08:54.884] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:54.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:38:0) after 1m0s
[01:09:54.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:38:0)
[01:09:54.907] Timer tick, asking Buildbucket for the build status
[01:09:55.272] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:55.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:39:0) after 1m0s
[01:10:55.365] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:39:0)
[01:10:55.365] Timer tick, asking Buildbucket for the build status
[01:10:55.557] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:55.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:40:0) after 1m0s
[01:11:55.575] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:40:0)
[01:11:55.575] Timer tick, asking Buildbucket for the build status
[01:11:56.479] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:56.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:41:0) after 1m0s
[01:12:56.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:41:0)
[01:12:56.667] Timer tick, asking Buildbucket for the build status
[01:12:56.935] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:56.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:42:0) after 1m0s
[01:13:56.996] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:42:0)
[01:13:56.996] Timer tick, asking Buildbucket for the build status
[01:13:57.317] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:57.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:43:0) after 1m0s
[01:14:57.338] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:43:0)
[01:14:57.338] Timer tick, asking Buildbucket for the build status
[01:14:57.768] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:57.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:44:0) after 1m0s
[01:15:57.822] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:44:0)
[01:15:57.822] Timer tick, asking Buildbucket for the build status
[01:15:58.879] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:58.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:45:0) after 1m0s
[01:16:58.939] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:45:0)
[01:16:58.939] Timer tick, asking Buildbucket for the build status
[01:16:59.162] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:59.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:46:0) after 1m0s
[01:17:59.185] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:46:0)
[01:17:59.185] Timer tick, asking Buildbucket for the build status
[01:17:59.383] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:59.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:47:0) after 1m0s
[01:18:59.410] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:47:0)
[01:18:59.410] Timer tick, asking Buildbucket for the build status
[01:18:59.719] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:59.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:48:0) after 1m0s
[01:19:59.757] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:48:0)
[01:19:59.757] Timer tick, asking Buildbucket for the build status
[01:19:59.962] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:59.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:49:0) after 1m0s
[01:21:00.044] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:49:0)
[01:21:00.044] Timer tick, asking Buildbucket for the build status
[01:21:00.256] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:00.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:50:0) after 1m0s
[01:22:00.320] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:50:0)
[01:22:00.320] Timer tick, asking Buildbucket for the build status
[01:22:00.527] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:00.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:51:0) after 1m0s
[01:23:00.577] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:51:0)
[01:23:00.577] Timer tick, asking Buildbucket for the build status
[01:23:01.716] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:01.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:52:0) after 1m0s
[01:24:01.808] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:52:0)
[01:24:01.808] Timer tick, asking Buildbucket for the build status
[01:24:02.095] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:02.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:53:0) after 1m0s
[01:25:02.120] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:53:0)
[01:25:02.120] Timer tick, asking Buildbucket for the build status
[01:25:02.497] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:02.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:54:0) after 1m0s
[01:26:02.512] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:54:0)
[01:26:02.512] Timer tick, asking Buildbucket for the build status
[01:26:02.647] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:02.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:55:0) after 1m0s
[01:27:02.683] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:55:0)
[01:27:02.683] Timer tick, asking Buildbucket for the build status
[01:27:02.958] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:02.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:56:0) after 1m0s
[01:28:02.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:56:0)
[01:28:02.981] Timer tick, asking Buildbucket for the build status
[01:28:03.238] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:03.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:57:0) after 1m0s
[01:29:03.308] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:57:0)
[01:29:03.308] Timer tick, asking Buildbucket for the build status
[01:29:03.546] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:03.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:58:0) after 1m0s
[01:30:03.601] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:58:0)
[01:30:03.601] Timer tick, asking Buildbucket for the build status
[01:30:03.762] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:03.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:59:0) after 1m0s
[01:31:03.837] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:59:0)
[01:31:03.837] Timer tick, asking Buildbucket for the build status
[01:31:04.003] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:04.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:60:0) after 1m0s
[01:32:04.030] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:60:0)
[01:32:04.030] Timer tick, asking Buildbucket for the build status
[01:32:04.213] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:04.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:61:0) after 1m0s
[01:33:04.269] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:61:0)
[01:33:04.269] Timer tick, asking Buildbucket for the build status
[01:33:04.691] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:04.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:62:0) after 1m0s
[01:34:04.753] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:62:0)
[01:34:04.753] Timer tick, asking Buildbucket for the build status
[01:34:04.873] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:04.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:63:0) after 1m0s
[01:35:04.893] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:63:0)
[01:35:04.893] Timer tick, asking Buildbucket for the build status
[01:35:05.032] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:05.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:64:0) after 1m0s
[01:36:05.041] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:64:0)
[01:36:05.041] Timer tick, asking Buildbucket for the build status
[01:36:05.208] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:05.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:65:0) after 1m0s
[01:37:05.234] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:65:0)
[01:37:05.234] Timer tick, asking Buildbucket for the build status
[01:37:05.404] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:05.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:66:0) after 1m0s
[01:38:05.470] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:66:0)
[01:38:05.470] Timer tick, asking Buildbucket for the build status
[01:38:05.929] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:05.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:67:0) after 1m0s
[01:39:05.962] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:67:0)
[01:39:05.962] Timer tick, asking Buildbucket for the build status
[01:39:06.100] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:06.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:68:0) after 1m0s
[01:40:06.165] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:68:0)
[01:40:06.165] Timer tick, asking Buildbucket for the build status
[01:40:06.648] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:06.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:69:0) after 1m0s
[01:41:06.703] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:69:0)
[01:41:06.703] Timer tick, asking Buildbucket for the build status
[01:41:06.852] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:06.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:70:0) after 1m0s
[01:42:06.881] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:70:0)
[01:42:06.881] Timer tick, asking Buildbucket for the build status
[01:42:07.135] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:07.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:71:0) after 1m0s
[01:43:07.163] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:71:0)
[01:43:07.163] Timer tick, asking Buildbucket for the build status
[01:43:07.271] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:07.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:72:0) after 1m0s
[01:44:07.323] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:72:0)
[01:44:07.323] Timer tick, asking Buildbucket for the build status
[01:44:07.454] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:07.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:73:0) after 1m0s
[01:45:07.519] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:73:0)
[01:45:07.519] Timer tick, asking Buildbucket for the build status
[01:45:07.843] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:07.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:74:0) after 1m0s
[01:46:07.902] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:74:0)
[01:46:07.902] Timer tick, asking Buildbucket for the build status
[01:46:08.106] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:08.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:75:0) after 1m0s
[01:47:08.157] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:75:0)
[01:47:08.157] Timer tick, asking Buildbucket for the build status
[01:47:08.383] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:08.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:76:0) after 1m0s
[01:48:08.525] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:76:0)
[01:48:08.525] Timer tick, asking Buildbucket for the build status
[01:48:08.882] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:08.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:77:0) after 1m0s
[01:49:09.029] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:77:0)
[01:49:09.029] Timer tick, asking Buildbucket for the build status
[01:49:09.306] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:09.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:78:0) after 1m0s
[01:50:09.331] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:78:0)
[01:50:09.331] Timer tick, asking Buildbucket for the build status
[01:50:09.717] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:09.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:79:0) after 1m0s
[01:51:09.742] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:79:0)
[01:51:09.742] Timer tick, asking Buildbucket for the build status
[01:51:09.857] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:09.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:80:0) after 1m0s
[01:52:09.876] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:80:0)
[01:52:09.876] Timer tick, asking Buildbucket for the build status
[01:52:10.018] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:10.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:81:0) after 1m0s
[01:53:10.042] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:81:0)
[01:53:10.042] Timer tick, asking Buildbucket for the build status
[01:53:10.164] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:10.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:82:0) after 1m0s
[01:54:10.280] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:82:0)
[01:54:10.280] Timer tick, asking Buildbucket for the build status
[01:54:10.651] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:10.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:83:0) after 1m0s
[01:55:10.698] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:83:0)
[01:55:10.698] Timer tick, asking Buildbucket for the build status
[01:55:10.796] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:10.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:84:0) after 1m0s
[01:56:10.813] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:84:0)
[01:56:10.813] Timer tick, asking Buildbucket for the build status
[01:56:10.909] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:10.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:85:0) after 1m0s
[01:57:10.953] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:85:0)
[01:57:10.953] Timer tick, asking Buildbucket for the build status
[01:57:11.698] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:11.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:86:0) after 1m0s
[01:58:11.726] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:86:0)
[01:58:11.726] Timer tick, asking Buildbucket for the build status
[01:58:11.873] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:11.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:87:0) after 1m0s
[01:59:11.903] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:87:0)
[01:59:11.903] Timer tick, asking Buildbucket for the build status
[01:59:12.063] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:12.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:88:0) after 1m0s
[02:00:12.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:88:0)
[02:00:12.117] Timer tick, asking Buildbucket for the build status
[02:00:13.815] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:13.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:89:0) after 1m0s
[02:01:13.850] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:89:0)
[02:01:13.850] Timer tick, asking Buildbucket for the build status
[02:01:15.198] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:15.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:90:0) after 1m0s
[02:02:15.275] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:90:0)
[02:02:15.275] Timer tick, asking Buildbucket for the build status
[02:02:15.515] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:15.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:91:0) after 1m0s
[02:03:15.533] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:91:0)
[02:03:15.533] Timer tick, asking Buildbucket for the build status
[02:03:18.519] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:18.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:92:0) after 1m0s
[02:04:18.548] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:92:0)
[02:04:18.548] Timer tick, asking Buildbucket for the build status
[02:04:18.734] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:18.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:93:0) after 1m0s
[02:05:18.762] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:93:0)
[02:05:18.762] Timer tick, asking Buildbucket for the build status
[02:05:18.902] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:18.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:94:0) after 1m0s
[02:06:18.961] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:94:0)
[02:06:18.961] Timer tick, asking Buildbucket for the build status
[02:06:19.125] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:19.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:95:0) after 1m0s
[02:07:19.218] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:95:0)
[02:07:19.218] Timer tick, asking Buildbucket for the build status
[02:07:19.344] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:19.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:96:0) after 1m0s
[02:08:19.417] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:96:0)
[02:08:19.417] Timer tick, asking Buildbucket for the build status
[02:08:19.532] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:19.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:97:0) after 1m0s
[02:09:19.541] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:97:0)
[02:09:19.541] Timer tick, asking Buildbucket for the build status
[02:09:19.635] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:19.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:98:0) after 1m0s
[02:10:19.745] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:98:0)
[02:10:19.745] Timer tick, asking Buildbucket for the build status
[02:10:19.903] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:19.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:99:0) after 1m0s
[02:11:19.976] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:99:0)
[02:11:19.976] Timer tick, asking Buildbucket for the build status
[02:11:20.102] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:20.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:100:0) after 1m0s
[02:12:20.192] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:100:0)
[02:12:20.192] Timer tick, asking Buildbucket for the build status
[02:12:20.424] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:20.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:101:0) after 1m0s
[02:13:20.453] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:101:0)
[02:13:20.453] Timer tick, asking Buildbucket for the build status
[02:13:21.242] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:21.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:102:0) after 1m0s
[02:14:21.273] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:102:0)
[02:14:21.273] Timer tick, asking Buildbucket for the build status
[02:14:21.411] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:21.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:103:0) after 1m0s
[02:15:21.452] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:103:0)
[02:15:21.452] Timer tick, asking Buildbucket for the build status
[02:15:21.585] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:21.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:104:0) after 1m0s
[02:16:21.608] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:104:0)
[02:16:21.608] Timer tick, asking Buildbucket for the build status
[02:16:21.822] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:21.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:105:0) after 1m0s
[02:17:21.858] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:105:0)
[02:17:21.858] Timer tick, asking Buildbucket for the build status
[02:17:21.971] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:21.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:106:0) after 1m0s
[02:18:22.000] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:106:0)
[02:18:22.001] Timer tick, asking Buildbucket for the build status
[02:18:22.320] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:22.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:107:0) after 1m0s
[02:19:22.337] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:107:0)
[02:19:22.337] Timer tick, asking Buildbucket for the build status
[02:19:22.636] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:22.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:108:0) after 1m0s
[02:20:22.694] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:108:0)
[02:20:22.694] Timer tick, asking Buildbucket for the build status
[02:20:22.805] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:22.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:109:0) after 1m0s
[02:21:22.829] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:109:0)
[02:21:22.829] Timer tick, asking Buildbucket for the build status
[02:21:22.968] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:22.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:110:0) after 1m0s
[02:22:23.002] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:110:0)
[02:22:23.002] Timer tick, asking Buildbucket for the build status
[02:22:23.132] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:23.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:111:0) after 1m0s
[02:23:23.189] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:111:0)
[02:23:23.189] Timer tick, asking Buildbucket for the build status
[02:23:25.521] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:25.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:112:0) after 1m0s
[02:24:25.558] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:112:0)
[02:24:25.558] Timer tick, asking Buildbucket for the build status
[02:24:25.722] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:25.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:113:0) after 1m0s
[02:25:25.768] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:113:0)
[02:25:25.768] Timer tick, asking Buildbucket for the build status
[02:25:25.915] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:25.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:114:0) after 1m0s
[02:26:25.965] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:114:0)
[02:26:25.965] Timer tick, asking Buildbucket for the build status
[02:26:26.242] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:26.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:115:0) after 1m0s
[02:27:26.279] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:115:0)
[02:27:26.279] Timer tick, asking Buildbucket for the build status
[02:27:26.664] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:26.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:116:0) after 1m0s
[02:28:26.690] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:116:0)
[02:28:26.690] Timer tick, asking Buildbucket for the build status
[02:28:27.372] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:27.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:117:0) after 1m0s
[02:29:27.478] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:117:0)
[02:29:27.478] Timer tick, asking Buildbucket for the build status
[02:29:27.792] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:27.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:118:0) after 1m0s
[02:30:27.849] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:118:0)
[02:30:27.850] Timer tick, asking Buildbucket for the build status
[02:30:27.985] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:27.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:119:0) after 1m0s
[02:31:28.054] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:119:0)
[02:31:28.054] Timer tick, asking Buildbucket for the build status
[02:31:28.208] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:28.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:120:0) after 1m0s
[02:32:28.294] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:120:0)
[02:32:28.294] Timer tick, asking Buildbucket for the build status
[02:32:28.488] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:28.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:121:0) after 1m0s
[02:33:28.545] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:121:0)
[02:33:28.545] Timer tick, asking Buildbucket for the build status
[02:33:30.497] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:30.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:122:0) after 1m0s
[02:34:30.632] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:122:0)
[02:34:30.632] Timer tick, asking Buildbucket for the build status
[02:34:30.776] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:30.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:123:0) after 1m0s
[02:35:30.786] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:123:0)
[02:35:30.786] Timer tick, asking Buildbucket for the build status
[02:35:30.934] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:30.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:124:0) after 1m0s
[02:36:30.976] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:124:0)
[02:36:30.976] Timer tick, asking Buildbucket for the build status
[02:36:31.153] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:31.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:125:0) after 1m0s
[02:37:31.187] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:125:0)
[02:37:31.187] Timer tick, asking Buildbucket for the build status
[02:37:31.322] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:31.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:126:0) after 1m0s
[02:38:31.359] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:126:0)
[02:38:31.359] Timer tick, asking Buildbucket for the build status
[02:38:32.255] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:32.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:127:0) after 1m0s
[02:39:32.315] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:127:0)
[02:39:32.315] Timer tick, asking Buildbucket for the build status
[02:39:33.064] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:33.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:128:0) after 1m0s
[02:40:33.118] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:128:0)
[02:40:33.118] Timer tick, asking Buildbucket for the build status
[02:40:33.363] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:33.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:129:0) after 1m0s
[02:41:33.386] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:129:0)
[02:41:33.386] Timer tick, asking Buildbucket for the build status
[02:41:33.579] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:33.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:130:0) after 1m0s
[02:42:33.637] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:130:0)
[02:42:33.637] Timer tick, asking Buildbucket for the build status
[02:42:33.738] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:33.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:131:0) after 1m0s
[02:43:33.774] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:131:0)
[02:43:33.774] Timer tick, asking Buildbucket for the build status
[02:43:34.810] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:34.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:132:0) after 1m0s
[02:44:34.795] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:132:0)
[02:44:34.795] Timer tick, asking Buildbucket for the build status
[02:44:35.009] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:35.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:133:0) after 1m0s
[02:45:35.074] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:133:0)
[02:45:35.074] Timer tick, asking Buildbucket for the build status
[02:45:35.237] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:35.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:134:0) after 1m0s
[02:46:35.283] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:134:0)
[02:46:35.283] Timer tick, asking Buildbucket for the build status
[02:46:35.386] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:35.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:135:0) after 1m0s
[02:47:35.449] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:135:0)
[02:47:35.449] Timer tick, asking Buildbucket for the build status
[02:47:35.727] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:35.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:136:0) after 1m0s
[02:48:35.757] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:136:0)
[02:48:35.757] Timer tick, asking Buildbucket for the build status
[02:48:36.043] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:36.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:137:0) after 1m0s
[02:49:36.115] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:137:0)
[02:49:36.115] Timer tick, asking Buildbucket for the build status
[02:49:36.744] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:36.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:138:0) after 1m0s
[02:50:36.879] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:138:0)
[02:50:36.879] Timer tick, asking Buildbucket for the build status
[02:50:37.023] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:37.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:139:0) after 1m0s
[02:51:37.076] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:139:0)
[02:51:37.076] Timer tick, asking Buildbucket for the build status
[02:51:37.396] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:37.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:140:0) after 1m0s
[02:52:37.392] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:140:0)
[02:52:37.392] Timer tick, asking Buildbucket for the build status
[02:52:37.577] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:37.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:141:0) after 1m0s
[02:53:37.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:141:0)
[02:53:37.606] Timer tick, asking Buildbucket for the build status
[02:53:40.375] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:40.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:142:0) after 1m0s
[02:54:40.375] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:142:0)
[02:54:40.375] Timer tick, asking Buildbucket for the build status
[02:54:40.510] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:40.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:143:0) after 1m0s
[02:55:40.553] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:143:0)
[02:55:40.553] Timer tick, asking Buildbucket for the build status
[02:55:40.788] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:40.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:144:0) after 1m0s
[02:56:40.877] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:144:0)
[02:56:40.877] Timer tick, asking Buildbucket for the build status
[02:56:41.039] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:41.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:145:0) after 1m0s
[02:57:41.141] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:145:0)
[02:57:41.141] Timer tick, asking Buildbucket for the build status
[02:57:41.264] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:41.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:146:0) after 1m0s
[02:58:41.317] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:146:0)
[02:58:41.317] Timer tick, asking Buildbucket for the build status
[02:58:41.464] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:41.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:147:0) after 1m0s
[02:59:41.479] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:147:0)
[02:59:41.479] Timer tick, asking Buildbucket for the build status
[02:59:41.698] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:41.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:148:0) after 1m0s
[03:00:41.712] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:148:0)
[03:00:41.712] Timer tick, asking Buildbucket for the build status
[03:00:41.925] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:41.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:149:0) after 1m0s
[03:01:41.957] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:149:0)
[03:01:41.957] Timer tick, asking Buildbucket for the build status
[03:01:42.108] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:42.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:150:0) after 1m0s
[03:02:42.155] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:150:0)
[03:02:42.155] Timer tick, asking Buildbucket for the build status
[03:02:42.297] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:42.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:151:0) after 1m0s
[03:03:42.291] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:151:0)
[03:03:42.291] Timer tick, asking Buildbucket for the build status
[03:03:42.523] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:42.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:152:0) after 1m0s
[03:04:42.582] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:152:0)
[03:04:42.582] Timer tick, asking Buildbucket for the build status
[03:04:42.745] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:42.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:153:0) after 1m0s
[03:05:42.817] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:153:0)
[03:05:42.817] Timer tick, asking Buildbucket for the build status
[03:05:43.007] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:43.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:154:0) after 1m0s
[03:06:43.010] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:154:0)
[03:06:43.010] Timer tick, asking Buildbucket for the build status
[03:06:43.140] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:43.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:155:0) after 1m0s
[03:07:43.166] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:155:0)
[03:07:43.166] Timer tick, asking Buildbucket for the build status
[03:07:43.291] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:43.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:156:0) after 1m0s
[03:08:43.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:156:0)
[03:08:43.394] Timer tick, asking Buildbucket for the build status
[03:08:43.741] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:43.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:157:0) after 1m0s
[03:09:43.785] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:157:0)
[03:09:43.785] Timer tick, asking Buildbucket for the build status
[03:09:43.918] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:43.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:158:0) after 1m0s
[03:10:43.921] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:158:0)
[03:10:43.921] Timer tick, asking Buildbucket for the build status
[03:10:44.056] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:44.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:159:0) after 1m0s
[03:11:44.117] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:159:0)
[03:11:44.117] Timer tick, asking Buildbucket for the build status
[03:11:44.285] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:44.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:160:0) after 1m0s
[03:12:44.321] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:160:0)
[03:12:44.321] Timer tick, asking Buildbucket for the build status
[03:12:44.464] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:44.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:161:0) after 1m0s
[03:13:44.562] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:161:0)
[03:13:44.562] Timer tick, asking Buildbucket for the build status
[03:13:45.083] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:45.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:162:0) after 1m0s
[03:14:45.114] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:162:0)
[03:14:45.114] Timer tick, asking Buildbucket for the build status
[03:14:45.223] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:45.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:163:0) after 1m0s
[03:15:45.259] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:163:0)
[03:15:45.259] Timer tick, asking Buildbucket for the build status
[03:15:45.457] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:45.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:164:0) after 1m0s
[03:16:45.485] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:164:0)
[03:16:45.485] Timer tick, asking Buildbucket for the build status
[03:16:45.599] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:45.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:165:0) after 1m0s
[03:17:45.632] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:165:0)
[03:17:45.632] Timer tick, asking Buildbucket for the build status
[03:17:45.784] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:45.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:166:0) after 1m0s
[03:18:45.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:166:0)
[03:18:45.854] Timer tick, asking Buildbucket for the build status
[03:18:46.025] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:46.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:167:0) after 1m0s
[03:19:46.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:167:0)
[03:19:46.123] Timer tick, asking Buildbucket for the build status
[03:19:46.256] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:46.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:168:0) after 1m0s
[03:20:46.276] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:168:0)
[03:20:46.276] Timer tick, asking Buildbucket for the build status
[03:20:46.482] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:46.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:169:0) after 1m0s
[03:21:46.594] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:169:0)
[03:21:46.594] Timer tick, asking Buildbucket for the build status
[03:21:46.758] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:46.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:170:0) after 1m0s
[03:22:46.806] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:170:0)
[03:22:46.806] Timer tick, asking Buildbucket for the build status
[03:22:46.966] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:46.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:171:0) after 1m0s
[03:23:46.991] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:171:0)
[03:23:46.991] Timer tick, asking Buildbucket for the build status
[03:23:47.979] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:47.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:172:0) after 1m0s
[03:24:48.006] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:172:0)
[03:24:48.006] Timer tick, asking Buildbucket for the build status
[03:24:48.145] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:48.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:173:0) after 1m0s
[03:25:48.184] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:173:0)
[03:25:48.184] Timer tick, asking Buildbucket for the build status
[03:25:48.292] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:48.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:174:0) after 1m0s
[03:26:48.365] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:174:0)
[03:26:48.365] Timer tick, asking Buildbucket for the build status
[03:26:48.554] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:48.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:175:0) after 1m0s
[03:27:48.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:175:0)
[03:27:48.640] Timer tick, asking Buildbucket for the build status
[03:27:48.975] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:48.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:176:0) after 1m0s
[03:28:49.041] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:176:0)
[03:28:49.041] Timer tick, asking Buildbucket for the build status
[03:28:49.607] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:49.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:177:0) after 1m0s
[03:29:49.643] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:177:0)
[03:29:49.643] Timer tick, asking Buildbucket for the build status
[03:29:50.542] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:50.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:178:0) after 1m0s
[03:30:50.547] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:178:0)
[03:30:50.547] Timer tick, asking Buildbucket for the build status
[03:30:50.692] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:50.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:179:0) after 1m0s
[03:31:50.757] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:179:0)
[03:31:50.757] Timer tick, asking Buildbucket for the build status
[03:31:51.265] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:51.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:180:0) after 1m0s
[03:32:51.371] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:180:0)
[03:32:51.371] Timer tick, asking Buildbucket for the build status
[03:32:51.514] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:51.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:181:0) after 1m0s
[03:33:51.576] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:181:0)
[03:33:51.576] Timer tick, asking Buildbucket for the build status
[03:33:52.380] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:52.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:182:0) after 1m0s
[03:34:52.424] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:182:0)
[03:34:52.424] Timer tick, asking Buildbucket for the build status
[03:34:52.567] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:52.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:183:0) after 1m0s
[03:35:52.672] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:183:0)
[03:35:52.672] Timer tick, asking Buildbucket for the build status
[03:35:52.799] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:52.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:184:0) after 1m0s
[03:36:52.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:184:0)
[03:36:52.967] Timer tick, asking Buildbucket for the build status
[03:36:53.101] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:53.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:185:0) after 1m0s
[03:37:53.263] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:185:0)
[03:37:53.263] Timer tick, asking Buildbucket for the build status
[03:37:53.436] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:53.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:186:0) after 1m0s
[03:38:53.866] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:186:0)
[03:38:53.866] Timer tick, asking Buildbucket for the build status
[03:38:54.205] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:54.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:187:0) after 1m0s
[03:39:54.272] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:187:0)
[03:39:54.272] Timer tick, asking Buildbucket for the build status
[03:39:54.475] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:54.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:188:0) after 1m0s
[03:40:54.506] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:188:0)
[03:40:54.506] Timer tick, asking Buildbucket for the build status
[03:40:54.666] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:54.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:189:0) after 1m0s
[03:41:54.664] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:189:0)
[03:41:54.664] Timer tick, asking Buildbucket for the build status
[03:41:54.770] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:54.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:190:0) after 1m0s
[03:42:54.810] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:190:0)
[03:42:54.810] Timer tick, asking Buildbucket for the build status
[03:42:54.926] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:54.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:191:0) after 1m0s
[03:43:54.951] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:191:0)
[03:43:54.952] Timer tick, asking Buildbucket for the build status
[03:43:55.103] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:55.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:192:0) after 1m0s
[03:44:55.172] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:192:0)
[03:44:55.172] Timer tick, asking Buildbucket for the build status
[03:44:55.822] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:55.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:193:0) after 1m0s
[03:45:55.856] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:193:0)
[03:45:55.856] Timer tick, asking Buildbucket for the build status
[03:45:55.979] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:55.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:194:0) after 1m0s
[03:46:56.039] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:194:0)
[03:46:56.039] Timer tick, asking Buildbucket for the build status
[03:46:56.307] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:56.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:195:0) after 1m0s
[03:47:56.505] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:195:0)
[03:47:56.505] Timer tick, asking Buildbucket for the build status
[03:47:56.682] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:56.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:196:0) after 1m0s
[03:48:56.854] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:196:0)
[03:48:56.854] Timer tick, asking Buildbucket for the build status
[03:48:57.048] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:57.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:197:0) after 1m0s
[03:49:57.178] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:197:0)
[03:49:57.178] Timer tick, asking Buildbucket for the build status
[03:49:57.397] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:57.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:198:0) after 1m0s
[03:50:57.425] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:198:0)
[03:50:57.425] Timer tick, asking Buildbucket for the build status
[03:50:57.632] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:57.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:199:0) after 1m0s
[03:51:57.687] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:199:0)
[03:51:57.687] Timer tick, asking Buildbucket for the build status
[03:51:57.877] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:57.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:200:0) after 1m0s
[03:52:57.939] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:200:0)
[03:52:57.939] Timer tick, asking Buildbucket for the build status
[03:52:58.120] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:58.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:201:0) after 1m0s
[03:53:58.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:201:0)
[03:53:58.281] Timer tick, asking Buildbucket for the build status
[03:53:58.533] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:58.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:202:0) after 1m0s
[03:54:59.134] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:202:0)
[03:54:59.134] Timer tick, asking Buildbucket for the build status
[03:54:59.381] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:59.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:203:0) after 1m0s
[03:55:59.650] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:203:0)
[03:55:59.650] Timer tick, asking Buildbucket for the build status
[03:55:59.957] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:59.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:204:0) after 1m0s
[03:57:00.049] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:204:0)
[03:57:00.049] Timer tick, asking Buildbucket for the build status
[03:57:00.285] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:00.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:205:0) after 1m0s
[03:58:00.353] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:205:0)
[03:58:00.353] Timer tick, asking Buildbucket for the build status
[03:58:00.932] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:00.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:206:0) after 1m0s
[03:59:01.035] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:206:0)
[03:59:01.035] Timer tick, asking Buildbucket for the build status
[03:59:02.395] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:02.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:207:0) after 1m0s
[04:00:02.710] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:207:0)
[04:00:02.710] Timer tick, asking Buildbucket for the build status
[04:00:05.134] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:05.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:208:0) after 1m0s
[04:01:05.378] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:208:0)
[04:01:05.378] Timer tick, asking Buildbucket for the build status
[04:01:06.629] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:06.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:209:0) after 1m0s
[04:02:06.672] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:209:0)
[04:02:06.672] Timer tick, asking Buildbucket for the build status
[04:02:06.843] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:06.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:210:0) after 1m0s
[04:03:06.928] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:210:0)
[04:03:06.928] Timer tick, asking Buildbucket for the build status
[04:03:07.069] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:07.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:211:0) after 1m0s
[04:04:07.089] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:211:0)
[04:04:07.089] Timer tick, asking Buildbucket for the build status
[04:04:07.256] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:07.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:212:0) after 1m0s
[04:05:07.352] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:212:0)
[04:05:07.352] Timer tick, asking Buildbucket for the build status
[04:05:07.767] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:07.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:213:0) after 1m0s
[04:06:07.959] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:213:0)
[04:06:07.959] Timer tick, asking Buildbucket for the build status
[04:06:08.109] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:08.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:214:0) after 1m0s
[04:07:08.327] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:214:0)
[04:07:08.327] Timer tick, asking Buildbucket for the build status
[04:07:08.698] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:08.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:215:0) after 1m0s
[04:08:08.841] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:215:0)
[04:08:08.841] Timer tick, asking Buildbucket for the build status
[04:08:09.210] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:09.210] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:216:0) after 1m0s
[04:09:09.323] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:216:0)
[04:09:09.323] Timer tick, asking Buildbucket for the build status
[04:09:09.684] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:09.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:217:0) after 1m0s
[04:10:09.813] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:217:0)
[04:10:09.813] Timer tick, asking Buildbucket for the build status
[04:10:10.157] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:10.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:218:0) after 1m0s
[04:11:10.239] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:218:0)
[04:11:10.239] Timer tick, asking Buildbucket for the build status
[04:11:10.361] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:10.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:219:0) after 1m0s
[04:12:10.379] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:219:0)
[04:12:10.379] Timer tick, asking Buildbucket for the build status
[04:12:10.621] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:10.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:220:0) after 1m0s
[04:13:10.715] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:220:0)
[04:13:10.715] Timer tick, asking Buildbucket for the build status
[04:13:10.845] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:10.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:221:0) after 1m0s
[04:14:10.940] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:221:0)
[04:14:10.940] Timer tick, asking Buildbucket for the build status
[04:14:11.274] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:11.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:222:0) after 1m0s
[04:15:11.279] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:222:0)
[04:15:11.279] Timer tick, asking Buildbucket for the build status
[04:15:11.692] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:11.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:223:0) after 1m0s
[04:16:11.733] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:223:0)
[04:16:11.733] Timer tick, asking Buildbucket for the build status
[04:16:11.933] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:11.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:224:0) after 1m0s
[04:17:11.967] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:224:0)
[04:17:11.967] Timer tick, asking Buildbucket for the build status
[04:17:12.140] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:12.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:225:0) after 1m0s
[04:18:12.181] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:225:0)
[04:18:12.181] Timer tick, asking Buildbucket for the build status
[04:18:12.299] Build 8884627871627805408: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:12.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050057935237886560:226:0) after 1m0s
[04:18:40.890] Received PubSub notification, asking Buildbucket for the build status
[04:18:41.346] Build 8884627871627805408: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:18:41.346] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "completed_ts": "1585369119760441",
    "created_by": "project:nacl",
    "created_ts": "1585355610209064",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884627871627805408",
    "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\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"title\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}}",
    "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\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"title\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\"}]}, \"bot_id\": \"luci-flex-ci-xenial-10-5f4d\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-64-validator-opt\", \"buildnumber\": 132, \"got_revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"mastername\": \"client.nacl\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ebf11aaad97fde7f91b1c72e0dddaf1653d41f77\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_8c2b8eb547846015ec3d468eb69fd2552fa0ea4cc78e414f9bfe9b2362755d86_v2\", \"builder_c4e0d2919c9449e57616d926859a612456f61676d96fb8625e0b44c6d892c145_v2\", \"builder_d6e34fbf0b97f42009d0e08c4c2190eabfc3f965b1a56dffd678d628cf424e8a_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-10-5f4d\"], \"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": "1585355626956131",
    "status": "COMPLETED",
    "status_changed_ts": "1585355627688559",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/132",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ebf11aaad97fde7f91b1c72e0dddaf1653d41f77",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050057935237886560",
      "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/8884627871627805408/+/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:4b37606c3f595310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585369120241049",
    "url": "https://ci.chromium.org/b/8884627871627805408",
    "utcnow_ts": "1585369121262844"
  }
}
[04:18:41.346] Invocation finished in 3h45m13.963954994s with status FAILED