LUCI Scheduler :: nacl :: linux-pnacl-x86_64-tests-x86_32

Status: FAILED

Triggered by: -

Duration: 5 hours

Actions:

Definition (rev 1c82d86159c2071b02a7ddf08ffd2ae0bff92186)

buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.nacl.toolchain"
  builder: "linux-pnacl-x86_64-tests-x86_32"
>

Incoming triggers

b599fb88f59e617bfed2cf03729f24be5c378e2c (4 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[20:35:04.139] New invocation is queued and will start shortly
[20:35:10.874] Starting the invocation (attempt 1)
[20:35:10.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:35:10.921] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:35:10.921] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9047808009151081920",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"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\":\"b599fb88f59e617bfed2cf03729f24be5c378e2c\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@b599fb88f59e617bfed2cf03729f24be5c378e2c\",\"title\":\"b599fb88f59e617bfed2cf03729f24be5c378e2c\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/b599fb88f59e617bfed2cf03729f24be5c378e2c\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9047808009151081920",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/b599fb88f59e617bfed2cf03729f24be5c378e2c",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b599fb88f59e617bfed2cf03729f24be5c378e2c",
    "gitiles_ref:refs/heads/master"
  ]
}
[20:35:13.337] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1587501311144847",
    "id": "8882377941124084352",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"title\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/b599fb88f59e617bfed2cf03729f24be5c378e2c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1587501312775241",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5155",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/b599fb88f59e617bfed2cf03729f24be5c378e2c",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b599fb88f59e617bfed2cf03729f24be5c378e2c",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9047808009151081920",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8882377941124084352/+/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": "1587501312775381",
    "url": "https://ci.chromium.org/b/8882377941124084352",
    "utcnow_ts": "1587501313301094"
  }
}
[20:35:13.337] Task URL: https://ci.chromium.org/b/8882377941124084352
[20:35:13.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:2:0) after 1m0s
[20:36:13.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:2:0)
[20:36:13.397] Timer tick, asking Buildbucket for the build status
[20:36:13.537] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:13.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:3:0) after 1m0s
[20:37:13.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:3:0)
[20:37:13.622] Timer tick, asking Buildbucket for the build status
[20:37:13.828] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:13.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:4:0) after 1m0s
[20:38:13.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:4:0)
[20:38:13.894] Timer tick, asking Buildbucket for the build status
[20:38:14.091] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:14.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:5:0) after 1m0s
[20:39:14.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:5:0)
[20:39:14.172] Timer tick, asking Buildbucket for the build status
[20:39:14.441] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:14.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:6:0) after 1m0s
[20:40:14.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:6:0)
[20:40:14.525] Timer tick, asking Buildbucket for the build status
[20:40:15.354] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:15.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:7:0) after 1m0s
[20:41:15.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:7:0)
[20:41:15.361] Timer tick, asking Buildbucket for the build status
[20:41:15.776] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:15.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:8:0) after 1m0s
[20:42:15.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:8:0)
[20:42:15.804] Timer tick, asking Buildbucket for the build status
[20:42:16.593] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:16.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:9:0) after 1m0s
[20:43:16.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:9:0)
[20:43:16.669] Timer tick, asking Buildbucket for the build status
[20:43:16.946] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:16.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:10:0) after 1m0s
[20:44:16.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:10:0)
[20:44:16.960] Timer tick, asking Buildbucket for the build status
[20:44:17.963] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:17.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:11:0) after 1m0s
[20:45:18.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:11:0)
[20:45:18.068] Timer tick, asking Buildbucket for the build status
[20:45:18.276] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:18.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:12:0) after 1m0s
[20:46:18.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:12:0)
[20:46:18.363] Timer tick, asking Buildbucket for the build status
[20:46:18.490] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:18.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:13:0) after 1m0s
[20:47:18.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:13:0)
[20:47:18.482] Timer tick, asking Buildbucket for the build status
[20:47:19.161] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:19.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:14:0) after 1m0s
[20:48:19.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:14:0)
[20:48:19.321] Timer tick, asking Buildbucket for the build status
[20:48:19.446] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:19.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:15:0) after 1m0s
[20:49:19.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:15:0)
[20:49:19.542] Timer tick, asking Buildbucket for the build status
[20:49:19.706] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:19.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:16:0) after 1m0s
[20:50:19.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:16:0)
[20:50:19.794] Timer tick, asking Buildbucket for the build status
[20:50:20.739] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:20.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:17:0) after 1m0s
[20:51:20.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:17:0)
[20:51:20.803] Timer tick, asking Buildbucket for the build status
[20:51:20.978] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:20.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:18:0) after 1m0s
[20:52:21.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:18:0)
[20:52:21.032] Timer tick, asking Buildbucket for the build status
[20:52:21.179] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:21.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:19:0) after 1m0s
[20:53:21.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:19:0)
[20:53:21.171] Timer tick, asking Buildbucket for the build status
[20:53:22.525] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:22.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:20:0) after 1m0s
[20:54:22.549] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:20:0)
[20:54:22.549] Timer tick, asking Buildbucket for the build status
[20:54:22.835] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:22.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:21:0) after 1m0s
[20:55:22.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:21:0)
[20:55:22.832] Timer tick, asking Buildbucket for the build status
[20:55:22.947] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:22.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:22:0) after 1m0s
[20:56:22.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:22:0)
[20:56:22.963] Timer tick, asking Buildbucket for the build status
[20:56:23.180] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:23.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:23:0) after 1m0s
[20:57:23.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:23:0)
[20:57:23.289] Timer tick, asking Buildbucket for the build status
[20:57:24.449] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:24.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:24:0) after 1m0s
[20:58:24.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:24:0)
[20:58:24.505] Timer tick, asking Buildbucket for the build status
[20:58:25.251] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:25.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:25:0) after 1m0s
[20:59:25.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:25:0)
[20:59:25.408] Timer tick, asking Buildbucket for the build status
[20:59:25.841] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:25.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:26:0) after 1m0s
[21:00:25.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:26:0)
[21:00:25.922] Timer tick, asking Buildbucket for the build status
[21:00:26.654] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:26.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:27:0) after 1m0s
[21:01:26.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:27:0)
[21:01:26.696] Timer tick, asking Buildbucket for the build status
[21:01:27.871] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:27.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:28:0) after 1m0s
[21:02:27.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:28:0)
[21:02:27.934] Timer tick, asking Buildbucket for the build status
[21:02:28.051] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:28.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:29:0) after 1m0s
[21:03:28.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:29:0)
[21:03:28.102] Timer tick, asking Buildbucket for the build status
[21:03:28.243] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:28.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:30:0) after 1m0s
[21:04:28.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:30:0)
[21:04:28.321] Timer tick, asking Buildbucket for the build status
[21:04:28.665] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:28.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:31:0) after 1m0s
[21:05:28.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:31:0)
[21:05:28.778] Timer tick, asking Buildbucket for the build status
[21:05:28.909] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:28.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:32:0) after 1m0s
[21:06:28.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:32:0)
[21:06:28.952] Timer tick, asking Buildbucket for the build status
[21:06:29.093] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:29.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:33:0) after 1m0s
[21:07:29.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:33:0)
[21:07:29.082] Timer tick, asking Buildbucket for the build status
[21:07:29.215] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:29.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:34:0) after 1m0s
[21:08:29.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:34:0)
[21:08:29.538] Timer tick, asking Buildbucket for the build status
[21:08:29.746] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:29.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:35:0) after 1m0s
[21:09:29.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:35:0)
[21:09:29.818] Timer tick, asking Buildbucket for the build status
[21:09:31.346] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:31.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:36:0) after 1m0s
[21:10:31.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:36:0)
[21:10:31.481] Timer tick, asking Buildbucket for the build status
[21:10:31.834] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:31.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:37:0) after 1m0s
[21:11:31.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:37:0)
[21:11:31.957] Timer tick, asking Buildbucket for the build status
[21:11:32.804] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:32.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:38:0) after 1m0s
[21:12:32.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:38:0)
[21:12:32.829] Timer tick, asking Buildbucket for the build status
[21:12:32.969] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:32.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:39:0) after 1m0s
[21:13:33.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:39:0)
[21:13:33.047] Timer tick, asking Buildbucket for the build status
[21:13:33.205] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:33.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:40:0) after 1m0s
[21:14:33.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:40:0)
[21:14:33.259] Timer tick, asking Buildbucket for the build status
[21:14:33.783] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:33.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:41:0) after 1m0s
[21:15:33.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:41:0)
[21:15:33.811] Timer tick, asking Buildbucket for the build status
[21:15:34.167] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:34.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:42:0) after 1m0s
[21:16:34.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:42:0)
[21:16:34.468] Timer tick, asking Buildbucket for the build status
[21:16:35.019] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:35.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:43:0) after 1m0s
[21:17:35.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:43:0)
[21:17:35.163] Timer tick, asking Buildbucket for the build status
[21:17:35.624] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:35.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:44:0) after 1m0s
[21:18:35.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:44:0)
[21:18:35.648] Timer tick, asking Buildbucket for the build status
[21:18:35.786] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:35.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:45:0) after 1m0s
[21:19:35.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:45:0)
[21:19:35.875] Timer tick, asking Buildbucket for the build status
[21:19:37.679] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:37.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:46:0) after 1m0s
[21:20:37.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:46:0)
[21:20:37.711] Timer tick, asking Buildbucket for the build status
[21:20:38.590] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:38.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:47:0) after 1m0s
[21:21:38.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:47:0)
[21:21:38.658] Timer tick, asking Buildbucket for the build status
[21:21:38.895] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:38.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:48:0) after 1m0s
[21:22:38.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:48:0)
[21:22:38.947] Timer tick, asking Buildbucket for the build status
[21:22:39.352] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:39.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:49:0) after 1m0s
[21:23:39.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:49:0)
[21:23:39.402] Timer tick, asking Buildbucket for the build status
[21:23:39.589] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:39.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:50:0) after 1m0s
[21:24:39.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:50:0)
[21:24:39.680] Timer tick, asking Buildbucket for the build status
[21:24:39.956] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:39.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:51:0) after 1m0s
[21:25:39.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:51:0)
[21:25:39.960] Timer tick, asking Buildbucket for the build status
[21:25:40.091] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:40.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:52:0) after 1m0s
[21:26:40.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:52:0)
[21:26:40.152] Timer tick, asking Buildbucket for the build status
[21:26:40.384] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:40.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:53:0) after 1m0s
[21:27:40.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:53:0)
[21:27:40.471] Timer tick, asking Buildbucket for the build status
[21:27:40.577] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:40.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:54:0) after 1m0s
[21:28:40.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:54:0)
[21:28:40.637] Timer tick, asking Buildbucket for the build status
[21:28:40.819] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:40.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:55:0) after 1m0s
[21:29:40.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:55:0)
[21:29:40.873] Timer tick, asking Buildbucket for the build status
[21:29:41.123] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:41.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:56:0) after 1m0s
[21:30:41.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:56:0)
[21:30:41.184] Timer tick, asking Buildbucket for the build status
[21:30:41.372] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:41.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:57:0) after 1m0s
[21:31:41.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:57:0)
[21:31:41.486] Timer tick, asking Buildbucket for the build status
[21:31:41.613] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:41.613] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:58:0) after 1m0s
[21:32:41.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:58:0)
[21:32:41.669] Timer tick, asking Buildbucket for the build status
[21:32:41.864] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:41.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:59:0) after 1m0s
[21:33:41.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:59:0)
[21:33:41.968] Timer tick, asking Buildbucket for the build status
[21:33:42.510] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:42.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:60:0) after 1m0s
[21:34:42.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:60:0)
[21:34:42.546] Timer tick, asking Buildbucket for the build status
[21:34:42.732] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:42.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:61:0) after 1m0s
[21:35:42.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:61:0)
[21:35:42.804] Timer tick, asking Buildbucket for the build status
[21:35:43.002] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:43.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:62:0) after 1m0s
[21:36:43.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:62:0)
[21:36:43.071] Timer tick, asking Buildbucket for the build status
[21:36:43.340] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:43.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:63:0) after 1m0s
[21:37:43.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:63:0)
[21:37:43.354] Timer tick, asking Buildbucket for the build status
[21:37:43.532] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:43.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:64:0) after 1m0s
[21:38:43.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:64:0)
[21:38:43.667] Timer tick, asking Buildbucket for the build status
[21:38:43.797] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:43.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:65:0) after 1m0s
[21:39:43.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:65:0)
[21:39:43.844] Timer tick, asking Buildbucket for the build status
[21:39:44.052] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:44.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:66:0) after 1m0s
[21:40:44.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:66:0)
[21:40:44.133] Timer tick, asking Buildbucket for the build status
[21:40:44.259] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:44.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:67:0) after 1m0s
[21:41:44.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:67:0)
[21:41:44.297] Timer tick, asking Buildbucket for the build status
[21:41:44.465] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:44.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:68:0) after 1m0s
[21:42:44.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:68:0)
[21:42:44.555] Timer tick, asking Buildbucket for the build status
[21:42:44.738] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:44.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:69:0) after 1m0s
[21:43:44.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:69:0)
[21:43:44.905] Timer tick, asking Buildbucket for the build status
[21:43:45.229] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:45.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:70:0) after 1m0s
[21:44:45.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:70:0)
[21:44:45.261] Timer tick, asking Buildbucket for the build status
[21:44:45.780] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:45.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:71:0) after 1m0s
[21:45:45.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:71:0)
[21:45:45.867] Timer tick, asking Buildbucket for the build status
[21:45:46.059] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:46.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:72:0) after 1m0s
[21:46:46.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:72:0)
[21:46:46.164] Timer tick, asking Buildbucket for the build status
[21:46:46.334] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:46.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:73:0) after 1m0s
[21:47:46.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:73:0)
[21:47:46.437] Timer tick, asking Buildbucket for the build status
[21:47:47.026] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:47.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:74:0) after 1m0s
[21:48:47.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:74:0)
[21:48:47.280] Timer tick, asking Buildbucket for the build status
[21:48:47.392] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:47.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:75:0) after 1m0s
[21:49:47.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:75:0)
[21:49:47.541] Timer tick, asking Buildbucket for the build status
[21:49:48.254] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:48.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:76:0) after 1m0s
[21:50:48.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:76:0)
[21:50:48.785] Timer tick, asking Buildbucket for the build status
[21:50:49.017] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:49.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:77:0) after 1m0s
[21:51:49.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:77:0)
[21:51:49.037] Timer tick, asking Buildbucket for the build status
[21:51:49.197] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:49.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:78:0) after 1m0s
[21:52:49.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:78:0)
[21:52:49.457] Timer tick, asking Buildbucket for the build status
[21:52:50.617] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:50.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:79:0) after 1m0s
[21:53:50.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:79:0)
[21:53:50.679] Timer tick, asking Buildbucket for the build status
[21:53:50.895] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:50.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:80:0) after 1m0s
[21:54:50.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:80:0)
[21:54:50.999] Timer tick, asking Buildbucket for the build status
[21:54:51.745] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:51.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:81:0) after 1m0s
[21:55:51.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:81:0)
[21:55:51.763] Timer tick, asking Buildbucket for the build status
[21:55:52.197] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:52.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:82:0) after 1m0s
[21:56:52.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:82:0)
[21:56:52.235] Timer tick, asking Buildbucket for the build status
[21:56:52.423] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:52.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:83:0) after 1m0s
[21:57:52.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:83:0)
[21:57:52.409] Timer tick, asking Buildbucket for the build status
[21:57:52.544] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:52.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:84:0) after 1m0s
[21:58:52.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:84:0)
[21:58:52.580] Timer tick, asking Buildbucket for the build status
[21:58:52.745] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:52.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:85:0) after 1m0s
[21:59:52.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:85:0)
[21:59:52.775] Timer tick, asking Buildbucket for the build status
[21:59:53.207] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:53.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:86:0) after 1m0s
[22:00:53.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:86:0)
[22:00:53.244] Timer tick, asking Buildbucket for the build status
[22:00:53.428] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:53.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:87:0) after 1m0s
[22:01:53.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:87:0)
[22:01:53.537] Timer tick, asking Buildbucket for the build status
[22:01:53.934] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:53.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:88:0) after 1m0s
[22:02:53.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:88:0)
[22:02:53.998] Timer tick, asking Buildbucket for the build status
[22:02:54.244] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:54.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:89:0) after 1m0s
[22:03:54.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:89:0)
[22:03:54.268] Timer tick, asking Buildbucket for the build status
[22:03:54.754] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:54.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:90:0) after 1m0s
[22:04:54.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:90:0)
[22:04:54.798] Timer tick, asking Buildbucket for the build status
[22:04:54.964] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:54.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:91:0) after 1m0s
[22:05:55.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:91:0)
[22:05:55.001] Timer tick, asking Buildbucket for the build status
[22:05:55.167] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:55.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:92:0) after 1m0s
[22:06:55.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:92:0)
[22:06:55.274] Timer tick, asking Buildbucket for the build status
[22:06:55.458] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:55.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:93:0) after 1m0s
[22:07:55.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:93:0)
[22:07:55.475] Timer tick, asking Buildbucket for the build status
[22:07:55.679] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:55.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:94:0) after 1m0s
[22:08:55.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:94:0)
[22:08:55.781] Timer tick, asking Buildbucket for the build status
[22:08:56.407] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:56.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:95:0) after 1m0s
[22:09:56.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:95:0)
[22:09:56.471] Timer tick, asking Buildbucket for the build status
[22:09:56.783] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:56.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:96:0) after 1m0s
[22:10:56.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:96:0)
[22:10:56.779] Timer tick, asking Buildbucket for the build status
[22:10:57.217] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:57.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:97:0) after 1m0s
[22:11:57.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:97:0)
[22:11:57.292] Timer tick, asking Buildbucket for the build status
[22:11:57.474] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:57.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:98:0) after 1m0s
[22:12:57.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:98:0)
[22:12:57.563] Timer tick, asking Buildbucket for the build status
[22:12:57.796] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:57.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:99:0) after 1m0s
[22:13:57.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:99:0)
[22:13:57.832] Timer tick, asking Buildbucket for the build status
[22:13:58.155] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:58.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:100:0) after 1m0s
[22:14:58.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:100:0)
[22:14:58.264] Timer tick, asking Buildbucket for the build status
[22:14:58.533] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:58.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:101:0) after 1m0s
[22:15:58.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:101:0)
[22:15:58.624] Timer tick, asking Buildbucket for the build status
[22:15:58.771] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:58.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:102:0) after 1m0s
[22:16:58.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:102:0)
[22:16:58.789] Timer tick, asking Buildbucket for the build status
[22:16:58.917] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:58.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:103:0) after 1m0s
[22:17:58.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:103:0)
[22:17:58.939] Timer tick, asking Buildbucket for the build status
[22:17:59.083] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:59.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:104:0) after 1m0s
[22:18:59.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:104:0)
[22:18:59.128] Timer tick, asking Buildbucket for the build status
[22:18:59.763] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:59.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:105:0) after 1m0s
[22:19:59.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:105:0)
[22:19:59.926] Timer tick, asking Buildbucket for the build status
[22:20:00.141] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:00.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:106:0) after 1m0s
[22:21:00.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:106:0)
[22:21:00.190] Timer tick, asking Buildbucket for the build status
[22:21:00.492] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:00.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:107:0) after 1m0s
[22:22:01.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:107:0)
[22:22:01.108] Timer tick, asking Buildbucket for the build status
[22:22:01.460] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:01.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:108:0) after 1m0s
[22:23:01.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:108:0)
[22:23:01.578] Timer tick, asking Buildbucket for the build status
[22:23:01.720] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:01.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:109:0) after 1m0s
[22:24:01.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:109:0)
[22:24:01.757] Timer tick, asking Buildbucket for the build status
[22:24:02.112] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:02.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:110:0) after 1m0s
[22:25:02.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:110:0)
[22:25:02.178] Timer tick, asking Buildbucket for the build status
[22:25:02.397] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:02.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:111:0) after 1m0s
[22:26:02.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:111:0)
[22:26:02.762] Timer tick, asking Buildbucket for the build status
[22:26:02.919] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:02.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:112:0) after 1m0s
[22:27:03.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:112:0)
[22:27:03.064] Timer tick, asking Buildbucket for the build status
[22:27:04.311] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:04.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:113:0) after 1m0s
[22:28:04.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:113:0)
[22:28:04.338] Timer tick, asking Buildbucket for the build status
[22:28:04.535] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:04.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:114:0) after 1m0s
[22:29:04.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:114:0)
[22:29:04.585] Timer tick, asking Buildbucket for the build status
[22:29:04.725] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:04.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:115:0) after 1m0s
[22:30:04.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:115:0)
[22:30:04.791] Timer tick, asking Buildbucket for the build status
[22:30:05.929] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:05.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:116:0) after 1m0s
[22:31:06.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:116:0)
[22:31:06.103] Timer tick, asking Buildbucket for the build status
[22:31:06.267] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:06.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:117:0) after 1m0s
[22:32:06.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:117:0)
[22:32:06.447] Timer tick, asking Buildbucket for the build status
[22:32:06.725] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:06.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:118:0) after 1m0s
[22:33:06.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:118:0)
[22:33:06.788] Timer tick, asking Buildbucket for the build status
[22:33:06.989] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:06.989] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:119:0) after 1m0s
[22:34:07.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:119:0)
[22:34:07.087] Timer tick, asking Buildbucket for the build status
[22:34:07.338] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:07.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:120:0) after 1m0s
[22:35:07.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:120:0)
[22:35:07.832] Timer tick, asking Buildbucket for the build status
[22:35:07.994] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:07.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:121:0) after 1m0s
[22:36:08.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:121:0)
[22:36:08.134] Timer tick, asking Buildbucket for the build status
[22:36:08.660] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:08.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:122:0) after 1m0s
[22:37:08.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:122:0)
[22:37:08.872] Timer tick, asking Buildbucket for the build status
[22:37:09.160] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:09.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:123:0) after 1m0s
[22:38:09.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:123:0)
[22:38:09.197] Timer tick, asking Buildbucket for the build status
[22:38:10.112] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:10.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:124:0) after 1m0s
[22:39:10.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:124:0)
[22:39:10.487] Timer tick, asking Buildbucket for the build status
[22:39:10.719] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:10.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:125:0) after 1m0s
[22:40:10.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:125:0)
[22:40:10.762] Timer tick, asking Buildbucket for the build status
[22:40:11.207] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:11.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:126:0) after 1m0s
[22:41:11.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:126:0)
[22:41:11.243] Timer tick, asking Buildbucket for the build status
[22:41:11.351] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:11.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:127:0) after 1m0s
[22:42:11.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:127:0)
[22:42:11.386] Timer tick, asking Buildbucket for the build status
[22:42:11.570] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:11.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:128:0) after 1m0s
[22:43:11.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:128:0)
[22:43:11.589] Timer tick, asking Buildbucket for the build status
[22:43:11.759] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:11.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:129:0) after 1m0s
[22:44:11.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:129:0)
[22:44:11.781] Timer tick, asking Buildbucket for the build status
[22:44:11.955] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:11.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:130:0) after 1m0s
[22:45:11.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:130:0)
[22:45:11.995] Timer tick, asking Buildbucket for the build status
[22:45:12.380] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:12.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:131:0) after 1m0s
[22:46:12.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:131:0)
[22:46:12.383] Timer tick, asking Buildbucket for the build status
[22:46:12.554] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:12.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:132:0) after 1m0s
[22:47:12.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:132:0)
[22:47:12.634] Timer tick, asking Buildbucket for the build status
[22:47:13.087] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:13.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:133:0) after 1m0s
[22:48:13.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:133:0)
[22:48:13.136] Timer tick, asking Buildbucket for the build status
[22:48:13.293] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:13.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:134:0) after 1m0s
[22:49:13.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:134:0)
[22:49:13.350] Timer tick, asking Buildbucket for the build status
[22:49:13.616] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:13.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:135:0) after 1m0s
[22:50:13.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:135:0)
[22:50:13.702] Timer tick, asking Buildbucket for the build status
[22:50:13.891] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:13.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:136:0) after 1m0s
[22:51:13.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:136:0)
[22:51:13.923] Timer tick, asking Buildbucket for the build status
[22:51:14.052] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:14.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:137:0) after 1m0s
[22:52:14.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:137:0)
[22:52:14.078] Timer tick, asking Buildbucket for the build status
[22:52:14.734] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:14.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:138:0) after 1m0s
[22:53:14.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:138:0)
[22:53:14.764] Timer tick, asking Buildbucket for the build status
[22:53:15.102] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:15.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:139:0) after 1m0s
[22:54:15.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:139:0)
[22:54:15.166] Timer tick, asking Buildbucket for the build status
[22:54:15.346] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:15.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:140:0) after 1m0s
[22:55:15.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:140:0)
[22:55:15.360] Timer tick, asking Buildbucket for the build status
[22:55:15.889] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:15.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:141:0) after 1m0s
[22:56:15.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:141:0)
[22:56:15.968] Timer tick, asking Buildbucket for the build status
[22:56:16.354] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:16.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:142:0) after 1m0s
[22:57:16.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:142:0)
[22:57:16.402] Timer tick, asking Buildbucket for the build status
[22:57:16.956] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:16.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:143:0) after 1m0s
[22:58:16.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:143:0)
[22:58:16.966] Timer tick, asking Buildbucket for the build status
[22:58:17.347] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:17.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:144:0) after 1m0s
[22:59:17.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:144:0)
[22:59:17.411] Timer tick, asking Buildbucket for the build status
[22:59:17.561] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:17.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:145:0) after 1m0s
[23:00:17.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:145:0)
[23:00:17.941] Timer tick, asking Buildbucket for the build status
[23:00:18.405] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:18.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:146:0) after 1m0s
[23:01:18.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:146:0)
[23:01:18.702] Timer tick, asking Buildbucket for the build status
[23:01:18.852] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:18.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:147:0) after 1m0s
[23:02:19.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:147:0)
[23:02:19.068] Timer tick, asking Buildbucket for the build status
[23:02:19.424] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:19.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:148:0) after 1m0s
[23:03:19.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:148:0)
[23:03:19.583] Timer tick, asking Buildbucket for the build status
[23:03:19.885] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:19.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:149:0) after 1m0s
[23:04:20.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:149:0)
[23:04:20.072] Timer tick, asking Buildbucket for the build status
[23:04:21.863] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:21.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:150:0) after 1m0s
[23:05:22.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:150:0)
[23:05:22.034] Timer tick, asking Buildbucket for the build status
[23:05:22.227] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:22.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:151:0) after 1m0s
[23:06:22.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:151:0)
[23:06:22.526] Timer tick, asking Buildbucket for the build status
[23:06:22.730] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:22.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:152:0) after 1m0s
[23:07:22.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:152:0)
[23:07:22.887] Timer tick, asking Buildbucket for the build status
[23:07:23.154] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:23.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:153:0) after 1m0s
[23:08:23.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:153:0)
[23:08:23.291] Timer tick, asking Buildbucket for the build status
[23:08:23.671] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:23.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:154:0) after 1m0s
[23:09:23.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:154:0)
[23:09:23.712] Timer tick, asking Buildbucket for the build status
[23:09:25.192] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:25.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:155:0) after 1m0s
[23:10:25.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:155:0)
[23:10:25.296] Timer tick, asking Buildbucket for the build status
[23:10:25.582] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:25.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:156:0) after 1m0s
[23:11:25.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:156:0)
[23:11:25.664] Timer tick, asking Buildbucket for the build status
[23:11:26.223] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:26.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:157:0) after 1m0s
[23:12:26.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:157:0)
[23:12:26.272] Timer tick, asking Buildbucket for the build status
[23:12:26.744] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:26.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:158:0) after 1m0s
[23:13:26.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:158:0)
[23:13:26.783] Timer tick, asking Buildbucket for the build status
[23:13:27.276] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:27.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:159:0) after 1m0s
[23:14:27.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:159:0)
[23:14:27.339] Timer tick, asking Buildbucket for the build status
[23:14:27.560] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:27.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:160:0) after 1m0s
[23:15:27.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:160:0)
[23:15:27.663] Timer tick, asking Buildbucket for the build status
[23:15:27.843] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:27.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:161:0) after 1m0s
[23:16:27.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:161:0)
[23:16:27.913] Timer tick, asking Buildbucket for the build status
[23:16:28.081] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:28.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:162:0) after 1m0s
[23:17:28.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:162:0)
[23:17:28.807] Timer tick, asking Buildbucket for the build status
[23:17:29.038] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:29.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:163:0) after 1m0s
[23:18:29.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:163:0)
[23:18:29.146] Timer tick, asking Buildbucket for the build status
[23:18:29.441] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:29.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:164:0) after 1m0s
[23:19:29.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:164:0)
[23:19:29.446] Timer tick, asking Buildbucket for the build status
[23:19:30.129] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:30.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:165:0) after 1m0s
[23:20:30.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:165:0)
[23:20:30.160] Timer tick, asking Buildbucket for the build status
[23:20:30.334] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:30.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:166:0) after 1m0s
[23:21:30.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:166:0)
[23:21:30.362] Timer tick, asking Buildbucket for the build status
[23:21:30.538] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:30.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:167:0) after 1m0s
[23:22:30.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:167:0)
[23:22:30.601] Timer tick, asking Buildbucket for the build status
[23:22:31.064] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:31.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:168:0) after 1m0s
[23:23:31.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:168:0)
[23:23:31.127] Timer tick, asking Buildbucket for the build status
[23:23:31.573] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:31.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:169:0) after 1m0s
[23:24:31.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:169:0)
[23:24:31.616] Timer tick, asking Buildbucket for the build status
[23:24:31.806] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:31.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:170:0) after 1m0s
[23:25:31.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:170:0)
[23:25:31.908] Timer tick, asking Buildbucket for the build status
[23:25:32.019] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:32.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:171:0) after 1m0s
[23:26:32.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:171:0)
[23:26:32.082] Timer tick, asking Buildbucket for the build status
[23:26:39.295] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:39.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:172:0) after 1m0s
[23:27:39.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:172:0)
[23:27:39.738] Timer tick, asking Buildbucket for the build status
[23:27:40.112] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:40.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:173:0) after 1m0s
[23:28:40.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:173:0)
[23:28:40.197] Timer tick, asking Buildbucket for the build status
[23:28:40.576] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:40.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:174:0) after 1m0s
[23:29:40.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:174:0)
[23:29:40.657] Timer tick, asking Buildbucket for the build status
[23:29:46.171] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:46.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:175:0) after 1m0s
[23:30:46.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:175:0)
[23:30:46.196] Timer tick, asking Buildbucket for the build status
[23:30:46.348] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:46.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:176:0) after 1m0s
[23:31:46.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:176:0)
[23:31:46.496] Timer tick, asking Buildbucket for the build status
[23:31:46.660] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:46.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:177:0) after 1m0s
[23:32:46.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:177:0)
[23:32:46.969] Timer tick, asking Buildbucket for the build status
[23:32:47.167] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:47.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:178:0) after 1m0s
[23:33:47.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:178:0)
[23:33:47.202] Timer tick, asking Buildbucket for the build status
[23:33:47.322] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:47.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:179:0) after 1m0s
[23:34:47.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:179:0)
[23:34:47.774] Timer tick, asking Buildbucket for the build status
[23:34:48.161] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:48.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:180:0) after 1m0s
[23:35:48.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:180:0)
[23:35:48.333] Timer tick, asking Buildbucket for the build status
[23:35:49.001] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:49.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:181:0) after 1m0s
[23:36:49.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:181:0)
[23:36:49.042] Timer tick, asking Buildbucket for the build status
[23:36:49.185] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:49.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:182:0) after 1m0s
[23:37:49.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:182:0)
[23:37:49.266] Timer tick, asking Buildbucket for the build status
[23:37:49.805] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:49.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:183:0) after 1m0s
[23:38:49.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:183:0)
[23:38:49.852] Timer tick, asking Buildbucket for the build status
[23:38:50.451] Build 8882377941124084352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:50.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:184:0) after 1m0s
[23:39:24.955] Received PubSub notification, asking Buildbucket for the build status
[23:39:25.055] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:50.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:184:0)
[23:39:50.588] Timer tick, asking Buildbucket for the build status
[23:39:51.501] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:51.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:186:0) after 1m0s
[23:40:51.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:186:0)
[23:40:51.687] Timer tick, asking Buildbucket for the build status
[23:40:51.812] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:51.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:187:0) after 1m0s
[23:41:51.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:187:0)
[23:41:51.899] Timer tick, asking Buildbucket for the build status
[23:41:52.054] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:52.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:188:0) after 1m0s
[23:42:52.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:188:0)
[23:42:52.515] Timer tick, asking Buildbucket for the build status
[23:42:52.654] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:52.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:189:0) after 1m0s
[23:43:52.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:189:0)
[23:43:52.992] Timer tick, asking Buildbucket for the build status
[23:43:53.108] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:53.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:190:0) after 1m0s
[23:44:53.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:190:0)
[23:44:53.138] Timer tick, asking Buildbucket for the build status
[23:44:54.133] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:54.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:191:0) after 1m0s
[23:45:54.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:191:0)
[23:45:54.211] Timer tick, asking Buildbucket for the build status
[23:45:54.595] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:54.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:192:0) after 1m0s
[23:46:54.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:192:0)
[23:46:54.708] Timer tick, asking Buildbucket for the build status
[23:46:54.905] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:54.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:193:0) after 1m0s
[23:47:55.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:193:0)
[23:47:55.062] Timer tick, asking Buildbucket for the build status
[23:47:55.255] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:55.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:194:0) after 1m0s
[23:48:55.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:194:0)
[23:48:55.327] Timer tick, asking Buildbucket for the build status
[23:48:55.688] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:55.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:195:0) after 1m0s
[23:49:55.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:195:0)
[23:49:55.770] Timer tick, asking Buildbucket for the build status
[23:49:56.134] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:56.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:196:0) after 1m0s
[23:50:56.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:196:0)
[23:50:56.185] Timer tick, asking Buildbucket for the build status
[23:50:56.901] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:56.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:197:0) after 1m0s
[23:51:56.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:197:0)
[23:51:56.996] Timer tick, asking Buildbucket for the build status
[23:51:57.127] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:57.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:198:0) after 1m0s
[23:52:57.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:198:0)
[23:52:57.250] Timer tick, asking Buildbucket for the build status
[23:52:57.432] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:57.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:199:0) after 1m0s
[23:53:57.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:199:0)
[23:53:57.456] Timer tick, asking Buildbucket for the build status
[23:53:57.624] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:57.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:200:0) after 1m0s
[23:54:57.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:200:0)
[23:54:57.735] Timer tick, asking Buildbucket for the build status
[23:54:58.054] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:58.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:201:0) after 1m0s
[23:55:58.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:201:0)
[23:55:58.146] Timer tick, asking Buildbucket for the build status
[23:55:58.316] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:58.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:202:0) after 1m0s
[23:56:58.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:202:0)
[23:56:58.397] Timer tick, asking Buildbucket for the build status
[23:56:58.537] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:203:0) after 1m0s
[23:57:58.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:203:0)
[23:57:58.576] Timer tick, asking Buildbucket for the build status
[23:57:58.902] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:58.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:204:0) after 1m0s
[23:58:58.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:204:0)
[23:58:58.956] Timer tick, asking Buildbucket for the build status
[23:58:59.538] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:205:0) after 1m0s
[23:59:59.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:205:0)
[23:59:59.649] Timer tick, asking Buildbucket for the build status
[23:59:59.916] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:59.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:206:0) after 1m0s
[00:01:00.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:206:0)
[00:01:00.121] Timer tick, asking Buildbucket for the build status
[00:01:00.497] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:00.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:207:0) after 1m0s
[00:02:00.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:207:0)
[00:02:00.638] Timer tick, asking Buildbucket for the build status
[00:02:00.767] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:00.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:208:0) after 1m0s
[00:03:01.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:208:0)
[00:03:01.394] Timer tick, asking Buildbucket for the build status
[00:03:01.559] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:01.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:209:0) after 1m0s
[00:04:02.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:209:0)
[00:04:02.374] Timer tick, asking Buildbucket for the build status
[00:04:02.815] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:02.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:210:0) after 1m0s
[00:05:03.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:210:0)
[00:05:03.381] Timer tick, asking Buildbucket for the build status
[00:05:03.682] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:03.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:211:0) after 1m0s
[00:06:03.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:211:0)
[00:06:03.767] Timer tick, asking Buildbucket for the build status
[00:06:04.104] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:04.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:212:0) after 1m0s
[00:07:04.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:212:0)
[00:07:04.367] Timer tick, asking Buildbucket for the build status
[00:07:04.784] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:04.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:213:0) after 1m0s
[00:08:04.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:213:0)
[00:08:04.975] Timer tick, asking Buildbucket for the build status
[00:08:05.453] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:05.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:214:0) after 1m0s
[00:09:05.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:214:0)
[00:09:05.706] Timer tick, asking Buildbucket for the build status
[00:09:05.852] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:05.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:215:0) after 1m0s
[00:10:06.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:215:0)
[00:10:06.375] Timer tick, asking Buildbucket for the build status
[00:10:06.597] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:06.597] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:216:0) after 1m0s
[00:11:07.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:216:0)
[00:11:07.021] Timer tick, asking Buildbucket for the build status
[00:11:07.531] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:07.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:217:0) after 1m0s
[00:12:07.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:217:0)
[00:12:07.802] Timer tick, asking Buildbucket for the build status
[00:12:07.976] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:07.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:218:0) after 1m0s
[00:13:08.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:218:0)
[00:13:08.032] Timer tick, asking Buildbucket for the build status
[00:13:08.160] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:08.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:219:0) after 1m0s
[00:14:08.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:219:0)
[00:14:08.323] Timer tick, asking Buildbucket for the build status
[00:14:10.824] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:10.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:220:0) after 1m0s
[00:15:10.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:220:0)
[00:15:10.877] Timer tick, asking Buildbucket for the build status
[00:15:11.280] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:11.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:221:0) after 1m0s
[00:16:11.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:221:0)
[00:16:11.321] Timer tick, asking Buildbucket for the build status
[00:16:11.421] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:11.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:222:0) after 1m0s
[00:17:11.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:222:0)
[00:17:11.443] Timer tick, asking Buildbucket for the build status
[00:17:11.817] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:11.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:223:0) after 1m0s
[00:18:11.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:223:0)
[00:18:11.870] Timer tick, asking Buildbucket for the build status
[00:18:12.027] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:12.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:224:0) after 1m0s
[00:19:12.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:224:0)
[00:19:12.190] Timer tick, asking Buildbucket for the build status
[00:19:12.415] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:12.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:225:0) after 1m0s
[00:20:12.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:225:0)
[00:20:12.418] Timer tick, asking Buildbucket for the build status
[00:20:12.901] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:12.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:226:0) after 1m0s
[00:21:12.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:226:0)
[00:21:12.941] Timer tick, asking Buildbucket for the build status
[00:21:13.145] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:13.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:227:0) after 1m0s
[00:22:13.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:227:0)
[00:22:13.124] Timer tick, asking Buildbucket for the build status
[00:22:13.262] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:13.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:228:0) after 1m0s
[00:23:13.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:228:0)
[00:23:13.356] Timer tick, asking Buildbucket for the build status
[00:23:13.588] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:13.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:229:0) after 1m0s
[00:24:13.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:229:0)
[00:24:13.829] Timer tick, asking Buildbucket for the build status
[00:24:14.151] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:14.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:230:0) after 1m0s
[00:25:14.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:230:0)
[00:25:14.250] Timer tick, asking Buildbucket for the build status
[00:25:14.400] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:14.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:231:0) after 1m0s
[00:26:14.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:231:0)
[00:26:14.533] Timer tick, asking Buildbucket for the build status
[00:26:14.736] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:14.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:232:0) after 1m0s
[00:27:14.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:232:0)
[00:27:14.768] Timer tick, asking Buildbucket for the build status
[00:27:15.109] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:15.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:233:0) after 1m0s
[00:28:15.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:233:0)
[00:28:15.138] Timer tick, asking Buildbucket for the build status
[00:28:15.643] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:15.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:234:0) after 1m0s
[00:29:16.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:234:0)
[00:29:16.177] Timer tick, asking Buildbucket for the build status
[00:29:16.804] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:16.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:235:0) after 1m0s
[00:30:17.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:235:0)
[00:30:17.009] Timer tick, asking Buildbucket for the build status
[00:30:17.242] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:17.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:236:0) after 1m0s
[00:31:17.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:236:0)
[00:31:17.487] Timer tick, asking Buildbucket for the build status
[00:31:17.634] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:17.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:237:0) after 1m0s
[00:32:17.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:237:0)
[00:32:17.754] Timer tick, asking Buildbucket for the build status
[00:32:18.179] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:18.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:238:0) after 1m0s
[00:33:18.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:238:0)
[00:33:18.368] Timer tick, asking Buildbucket for the build status
[00:33:18.505] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:18.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:239:0) after 1m0s
[00:34:18.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:239:0)
[00:34:18.698] Timer tick, asking Buildbucket for the build status
[00:34:18.895] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:18.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:240:0) after 1m0s
[00:35:18.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:240:0)
[00:35:18.923] Timer tick, asking Buildbucket for the build status
[00:35:19.099] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:19.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:241:0) after 1m0s
[00:36:19.144] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:241:0)
[00:36:19.144] Timer tick, asking Buildbucket for the build status
[00:36:19.291] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:19.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:242:0) after 1m0s
[00:37:19.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:242:0)
[00:37:19.314] Timer tick, asking Buildbucket for the build status
[00:37:19.460] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:19.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:243:0) after 1m0s
[00:38:19.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:243:0)
[00:38:19.496] Timer tick, asking Buildbucket for the build status
[00:38:19.707] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:19.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:244:0) after 1m0s
[00:39:19.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:244:0)
[00:39:19.801] Timer tick, asking Buildbucket for the build status
[00:39:19.986] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:19.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:245:0) after 1m0s
[00:40:20.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:245:0)
[00:40:20.065] Timer tick, asking Buildbucket for the build status
[00:40:20.204] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:20.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:246:0) after 1m0s
[00:41:20.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:246:0)
[00:41:20.353] Timer tick, asking Buildbucket for the build status
[00:41:22.429] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:22.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:247:0) after 1m0s
[00:42:22.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:247:0)
[00:42:22.453] Timer tick, asking Buildbucket for the build status
[00:42:22.607] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:22.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:248:0) after 1m0s
[00:43:22.662] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:248:0)
[00:43:22.662] Timer tick, asking Buildbucket for the build status
[00:43:22.982] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:22.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:249:0) after 1m0s
[00:44:23.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:249:0)
[00:44:23.107] Timer tick, asking Buildbucket for the build status
[00:44:23.283] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:23.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:250:0) after 1m0s
[00:45:23.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:250:0)
[00:45:23.332] Timer tick, asking Buildbucket for the build status
[00:45:24.049] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:24.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:251:0) after 1m0s
[00:46:24.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:251:0)
[00:46:24.102] Timer tick, asking Buildbucket for the build status
[00:46:24.325] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:24.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:252:0) after 1m0s
[00:47:24.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:252:0)
[00:47:24.419] Timer tick, asking Buildbucket for the build status
[00:47:24.615] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:24.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:253:0) after 1m0s
[00:48:24.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:253:0)
[00:48:24.690] Timer tick, asking Buildbucket for the build status
[00:48:24.823] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:24.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:254:0) after 1m0s
[00:49:24.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:254:0)
[00:49:24.868] Timer tick, asking Buildbucket for the build status
[00:49:25.035] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:25.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:255:0) after 1m0s
[00:50:25.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:255:0)
[00:50:25.169] Timer tick, asking Buildbucket for the build status
[00:50:25.783] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:25.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:256:0) after 1m0s
[00:51:25.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:256:0)
[00:51:25.836] Timer tick, asking Buildbucket for the build status
[00:51:26.167] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:26.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:257:0) after 1m0s
[00:52:26.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:257:0)
[00:52:26.364] Timer tick, asking Buildbucket for the build status
[00:52:26.742] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:26.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:258:0) after 1m0s
[00:53:26.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:258:0)
[00:53:26.885] Timer tick, asking Buildbucket for the build status
[00:53:27.024] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:27.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:259:0) after 1m0s
[00:54:27.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:259:0)
[00:54:27.159] Timer tick, asking Buildbucket for the build status
[00:54:28.334] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:28.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:260:0) after 1m0s
[00:55:28.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:260:0)
[00:55:28.666] Timer tick, asking Buildbucket for the build status
[00:55:28.937] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:28.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:261:0) after 1m0s
[00:56:29.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:261:0)
[00:56:29.033] Timer tick, asking Buildbucket for the build status
[00:56:29.187] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:29.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:262:0) after 1m0s
[00:57:29.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:262:0)
[00:57:29.234] Timer tick, asking Buildbucket for the build status
[00:57:29.356] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:29.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:263:0) after 1m0s
[00:58:29.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:263:0)
[00:58:29.510] Timer tick, asking Buildbucket for the build status
[00:58:29.669] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:29.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:264:0) after 1m0s
[00:59:29.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:264:0)
[00:59:29.754] Timer tick, asking Buildbucket for the build status
[00:59:30.374] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:30.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:265:0) after 1m0s
[01:00:30.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:265:0)
[01:00:30.405] Timer tick, asking Buildbucket for the build status
[01:00:30.801] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:30.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:266:0) after 1m0s
[01:01:30.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:266:0)
[01:01:30.845] Timer tick, asking Buildbucket for the build status
[01:01:31.053] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:31.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:267:0) after 1m0s
[01:02:31.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:267:0)
[01:02:31.101] Timer tick, asking Buildbucket for the build status
[01:02:31.535] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:31.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:268:0) after 1m0s
[01:03:31.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:268:0)
[01:03:31.597] Timer tick, asking Buildbucket for the build status
[01:03:31.714] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:31.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:269:0) after 1m0s
[01:04:31.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:269:0)
[01:04:31.753] Timer tick, asking Buildbucket for the build status
[01:04:32.518] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:32.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:270:0) after 1m0s
[01:05:32.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:270:0)
[01:05:32.602] Timer tick, asking Buildbucket for the build status
[01:05:32.718] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:32.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:271:0) after 1m0s
[01:06:32.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:271:0)
[01:06:32.771] Timer tick, asking Buildbucket for the build status
[01:06:33.488] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:33.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:272:0) after 1m0s
[01:07:33.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:272:0)
[01:07:33.552] Timer tick, asking Buildbucket for the build status
[01:07:33.734] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:33.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:273:0) after 1m0s
[01:08:33.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:273:0)
[01:08:33.773] Timer tick, asking Buildbucket for the build status
[01:08:33.918] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:33.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:274:0) after 1m0s
[01:09:33.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:274:0)
[01:09:33.932] Timer tick, asking Buildbucket for the build status
[01:09:34.285] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:34.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:275:0) after 1m0s
[01:10:34.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:275:0)
[01:10:34.349] Timer tick, asking Buildbucket for the build status
[01:10:34.469] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:34.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:276:0) after 1m0s
[01:11:34.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:276:0)
[01:11:34.610] Timer tick, asking Buildbucket for the build status
[01:11:34.736] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:34.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:277:0) after 1m0s
[01:12:34.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:277:0)
[01:12:34.790] Timer tick, asking Buildbucket for the build status
[01:12:37.546] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:37.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:278:0) after 1m0s
[01:13:37.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:278:0)
[01:13:37.659] Timer tick, asking Buildbucket for the build status
[01:13:38.046] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:38.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:279:0) after 1m0s
[01:14:38.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:279:0)
[01:14:38.154] Timer tick, asking Buildbucket for the build status
[01:14:38.309] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:38.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:280:0) after 1m0s
[01:15:38.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:280:0)
[01:15:38.355] Timer tick, asking Buildbucket for the build status
[01:15:38.482] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:38.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:281:0) after 1m0s
[01:16:38.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:281:0)
[01:16:38.526] Timer tick, asking Buildbucket for the build status
[01:16:38.642] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:38.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:282:0) after 1m0s
[01:17:38.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:282:0)
[01:17:38.853] Timer tick, asking Buildbucket for the build status
[01:17:40.396] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:40.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:283:0) after 1m0s
[01:18:40.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:283:0)
[01:18:40.603] Timer tick, asking Buildbucket for the build status
[01:18:40.834] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:284:0) after 1m0s
[01:19:40.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:284:0)
[01:19:40.937] Timer tick, asking Buildbucket for the build status
[01:19:41.768] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:41.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:285:0) after 1m0s
[01:20:41.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:285:0)
[01:20:41.897] Timer tick, asking Buildbucket for the build status
[01:20:42.044] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:42.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:286:0) after 1m0s
[01:21:42.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:286:0)
[01:21:42.272] Timer tick, asking Buildbucket for the build status
[01:21:42.519] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:42.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:287:0) after 1m0s
[01:22:42.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:287:0)
[01:22:42.665] Timer tick, asking Buildbucket for the build status
[01:22:42.905] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:42.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:288:0) after 1m0s
[01:23:42.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:288:0)
[01:23:42.988] Timer tick, asking Buildbucket for the build status
[01:23:43.172] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:43.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:289:0) after 1m0s
[01:24:43.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:289:0)
[01:24:43.247] Timer tick, asking Buildbucket for the build status
[01:24:43.897] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:43.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:290:0) after 1m0s
[01:25:43.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:290:0)
[01:25:43.995] Timer tick, asking Buildbucket for the build status
[01:25:44.407] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:44.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:291:0) after 1m0s
[01:26:44.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:291:0)
[01:26:44.456] Timer tick, asking Buildbucket for the build status
[01:26:44.722] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:44.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:292:0) after 1m0s
[01:27:44.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:292:0)
[01:27:44.794] Timer tick, asking Buildbucket for the build status
[01:27:45.004] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:45.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:293:0) after 1m0s
[01:28:45.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:293:0)
[01:28:45.093] Timer tick, asking Buildbucket for the build status
[01:28:45.226] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:45.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:294:0) after 1m0s
[01:29:45.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:294:0)
[01:29:45.364] Timer tick, asking Buildbucket for the build status
[01:29:45.708] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:45.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:295:0) after 1m0s
[01:30:45.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:295:0)
[01:30:45.782] Timer tick, asking Buildbucket for the build status
[01:30:46.576] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:46.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:296:0) after 1m0s
[01:31:46.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:296:0)
[01:31:46.608] Timer tick, asking Buildbucket for the build status
[01:31:46.785] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:46.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:297:0) after 1m0s
[01:32:46.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:297:0)
[01:32:46.812] Timer tick, asking Buildbucket for the build status
[01:32:46.963] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:46.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:298:0) after 1m0s
[01:33:47.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:298:0)
[01:33:47.051] Timer tick, asking Buildbucket for the build status
[01:33:47.207] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:47.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:299:0) after 1m0s
[01:34:47.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:299:0)
[01:34:47.321] Timer tick, asking Buildbucket for the build status
[01:34:47.459] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:47.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:300:0) after 1m0s
[01:35:47.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:300:0)
[01:35:47.565] Timer tick, asking Buildbucket for the build status
[01:35:47.818] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:47.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:301:0) after 1m0s
[01:36:47.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:301:0)
[01:36:47.890] Timer tick, asking Buildbucket for the build status
[01:36:48.027] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:48.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:302:0) after 1m0s
[01:37:48.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:302:0)
[01:37:48.078] Timer tick, asking Buildbucket for the build status
[01:37:48.251] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:48.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:303:0) after 1m0s
[01:38:48.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:303:0)
[01:38:48.342] Timer tick, asking Buildbucket for the build status
[01:38:48.603] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:48.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:304:0) after 1m0s
[01:39:48.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:304:0)
[01:39:48.646] Timer tick, asking Buildbucket for the build status
[01:39:48.799] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:48.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:305:0) after 1m0s
[01:40:48.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:305:0)
[01:40:48.819] Timer tick, asking Buildbucket for the build status
[01:40:49.053] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:49.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:306:0) after 1m0s
[01:41:49.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:306:0)
[01:41:49.079] Timer tick, asking Buildbucket for the build status
[01:41:49.235] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:49.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:307:0) after 1m0s
[01:42:49.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:307:0)
[01:42:49.280] Timer tick, asking Buildbucket for the build status
[01:42:49.495] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:49.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:308:0) after 1m0s
[01:43:49.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:308:0)
[01:43:49.602] Timer tick, asking Buildbucket for the build status
[01:43:49.794] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:49.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:309:0) after 1m0s
[01:44:49.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:309:0)
[01:44:49.821] Timer tick, asking Buildbucket for the build status
[01:44:50.432] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:50.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:310:0) after 1m0s
[01:45:50.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:310:0)
[01:45:50.543] Timer tick, asking Buildbucket for the build status
[01:45:50.945] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:50.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:311:0) after 1m0s
[01:46:51.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:311:0)
[01:46:51.057] Timer tick, asking Buildbucket for the build status
[01:46:51.523] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:51.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:312:0) after 1m0s
[01:47:51.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:312:0)
[01:47:51.651] Timer tick, asking Buildbucket for the build status
[01:47:51.776] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:51.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:313:0) after 1m0s
[01:48:51.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:313:0)
[01:48:51.940] Timer tick, asking Buildbucket for the build status
[01:48:52.106] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:52.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:314:0) after 1m0s
[01:49:52.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:314:0)
[01:49:52.195] Timer tick, asking Buildbucket for the build status
[01:49:52.561] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:52.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:315:0) after 1m0s
[01:50:52.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:315:0)
[01:50:52.756] Timer tick, asking Buildbucket for the build status
[01:50:52.889] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:52.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:316:0) after 1m0s
[01:51:53.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:316:0)
[01:51:53.188] Timer tick, asking Buildbucket for the build status
[01:51:53.386] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:53.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:317:0) after 1m0s
[01:52:53.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:317:0)
[01:52:53.435] Timer tick, asking Buildbucket for the build status
[01:52:53.582] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:53.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:318:0) after 1m0s
[01:53:53.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:318:0)
[01:53:53.791] Timer tick, asking Buildbucket for the build status
[01:53:53.958] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:53.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:319:0) after 1m0s
[01:54:54.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:319:0)
[01:54:54.067] Timer tick, asking Buildbucket for the build status
[01:54:54.200] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:54.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:320:0) after 1m0s
[01:55:54.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:320:0)
[01:55:54.314] Timer tick, asking Buildbucket for the build status
[01:55:54.538] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:54.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:321:0) after 1m0s
[01:56:54.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:321:0)
[01:56:54.696] Timer tick, asking Buildbucket for the build status
[01:56:55.023] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:55.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:322:0) after 1m0s
[01:57:55.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:322:0)
[01:57:55.224] Timer tick, asking Buildbucket for the build status
[01:57:55.450] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:55.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:323:0) after 1m0s
[01:58:55.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:323:0)
[01:58:55.628] Timer tick, asking Buildbucket for the build status
[01:58:55.916] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:55.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:324:0) after 1m0s
[01:59:56.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:324:0)
[01:59:56.113] Timer tick, asking Buildbucket for the build status
[01:59:56.405] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:56.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:325:0) after 1m0s
[02:00:56.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:325:0)
[02:00:56.543] Timer tick, asking Buildbucket for the build status
[02:00:56.683] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:56.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:326:0) after 1m0s
[02:01:56.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:326:0)
[02:01:56.788] Timer tick, asking Buildbucket for the build status
[02:01:56.912] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:56.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:327:0) after 1m0s
[02:02:57.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:327:0)
[02:02:57.004] Timer tick, asking Buildbucket for the build status
[02:02:57.166] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:57.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:328:0) after 1m0s
[02:03:57.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:328:0)
[02:03:57.251] Timer tick, asking Buildbucket for the build status
[02:03:57.352] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:57.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:329:0) after 1m0s
[02:04:57.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:329:0)
[02:04:57.476] Timer tick, asking Buildbucket for the build status
[02:04:57.763] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:57.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:330:0) after 1m0s
[02:05:57.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:330:0)
[02:05:57.915] Timer tick, asking Buildbucket for the build status
[02:05:58.190] Build 8882377941124084352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:58.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9047808009151081920:331:0) after 1m0s
[02:06:10.495] Received PubSub notification, asking Buildbucket for the build status
[02:06:10.644] Build 8882377941124084352: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:06:10.644] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1587521169724776",
    "created_by": "project:nacl",
    "created_ts": "1587501311144847",
    "failure_reason": "BUILD_FAILURE",
    "id": "8882377941124084352",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"title\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/b599fb88f59e617bfed2cf03729f24be5c378e2c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}}",
    "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\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"title\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/b599fb88f59e617bfed2cf03729f24be5c378e2c\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-7wyi\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5155, \"got_revision\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"b599fb88f59e617bfed2cf03729f24be5c378e2c\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-7wyi\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4997-2128d8d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1587512363546552",
    "status": "COMPLETED",
    "status_changed_ts": "1587512364473646",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5155",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/b599fb88f59e617bfed2cf03729f24be5c378e2c",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b599fb88f59e617bfed2cf03729f24be5c378e2c",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9047808009151081920",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8882377941124084352/+/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:4bb74536ba427410",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1587521170052582",
    "url": "https://ci.chromium.org/b/8882377941124084352",
    "utcnow_ts": "1587521170631183"
  }
}
[02:06:10.644] Invocation finished in 5h31m6.538838869s with status FAILED