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

Status: FAILED

Triggered by: -

Duration: 7 hours

Actions:

Definition (rev 1c82d86159c2071b02a7ddf08ffd2ae0bff92186)

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

Incoming triggers

321c47ddeedcf7e87ffd2c127500e4ef9888fb69 (6 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[16:11:19.709] New invocation is queued and will start shortly
[16:11:21.290] Starting the invocation (attempt 1)
[16:11:21.329] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:11:21.329] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:11:21.329] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9089046221961837856",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_64",
    "scheduler_invocation_id:9089046221961837856",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
    "gitiles_ref:refs/heads/master"
  ]
}
[16:11:22.276] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548173481397402",
    "id": "8923616159529032640",
    "lease_expiration_ts": "1548324682017788",
    "lease_key": "654973623",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1548173482181774",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5060",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089046221961837856",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5060",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8923616159529032640",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8923616159529032640/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089046221961837856",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:4122e711faf74a549260c6de6a369a8fe79a9d3e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:428f261f0fefab10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548173482181890",
    "url": "https://ci.chromium.org/b/8923616159529032640",
    "utcnow_ts": "1548173482266007"
  }
}
[16:11:22.276] Task URL: https://ci.chromium.org/b/8923616159529032640
[16:11:22.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:2:0) after 1m0s
[16:12:22.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:2:0)
[16:12:22.296] Timer tick, asking Buildbucket for the build status
[16:12:22.461] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:22.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:3:0) after 1m0s
[16:13:22.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:3:0)
[16:13:22.479] Timer tick, asking Buildbucket for the build status
[16:13:22.516] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:22.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:4:0) after 1m0s
[16:14:22.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:4:0)
[16:14:22.531] Timer tick, asking Buildbucket for the build status
[16:14:22.636] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:22.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:5:0) after 1m0s
[16:15:22.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:5:0)
[16:15:22.651] Timer tick, asking Buildbucket for the build status
[16:15:22.695] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:22.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:6:0) after 1m0s
[16:16:22.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:6:0)
[16:16:22.734] Timer tick, asking Buildbucket for the build status
[16:16:22.876] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:22.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:7:0) after 1m0s
[16:17:22.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:7:0)
[16:17:22.933] Timer tick, asking Buildbucket for the build status
[16:17:23.017] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:23.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:8:0) after 1m0s
[16:18:23.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:8:0)
[16:18:23.041] Timer tick, asking Buildbucket for the build status
[16:18:23.128] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:23.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:9:0) after 1m0s
[16:19:23.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:9:0)
[16:19:23.145] Timer tick, asking Buildbucket for the build status
[16:19:23.233] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:23.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:10:0) after 1m0s
[16:20:23.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:10:0)
[16:20:23.249] Timer tick, asking Buildbucket for the build status
[16:20:23.576] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:23.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:11:0) after 1m0s
[16:21:23.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:11:0)
[16:21:23.593] Timer tick, asking Buildbucket for the build status
[16:21:23.710] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:23.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:12:0) after 1m0s
[16:22:23.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:12:0)
[16:22:23.779] Timer tick, asking Buildbucket for the build status
[16:22:23.846] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:23.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:13:0) after 1m0s
[16:23:23.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:13:0)
[16:23:23.859] Timer tick, asking Buildbucket for the build status
[16:23:23.896] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:23.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:14:0) after 1m0s
[16:24:23.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:14:0)
[16:24:23.912] Timer tick, asking Buildbucket for the build status
[16:24:23.962] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:23.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:15:0) after 1m0s
[16:25:24.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:15:0)
[16:25:24.054] Timer tick, asking Buildbucket for the build status
[16:25:24.141] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:24.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:16:0) after 1m0s
[16:26:24.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:16:0)
[16:26:24.165] Timer tick, asking Buildbucket for the build status
[16:26:24.299] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:24.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:17:0) after 1m0s
[16:27:24.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:17:0)
[16:27:24.316] Timer tick, asking Buildbucket for the build status
[16:27:24.418] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:24.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:18:0) after 1m0s
[16:28:24.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:18:0)
[16:28:24.449] Timer tick, asking Buildbucket for the build status
[16:28:24.549] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:24.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:19:0) after 1m0s
[16:29:24.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:19:0)
[16:29:24.563] Timer tick, asking Buildbucket for the build status
[16:29:24.620] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:24.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:20:0) after 1m0s
[16:30:24.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:20:0)
[16:30:24.637] Timer tick, asking Buildbucket for the build status
[16:30:24.817] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:24.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:21:0) after 1m0s
[16:31:24.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:21:0)
[16:31:24.832] Timer tick, asking Buildbucket for the build status
[16:31:24.878] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:24.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:22:0) after 1m0s
[16:32:24.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:22:0)
[16:32:24.922] Timer tick, asking Buildbucket for the build status
[16:32:24.990] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:24.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:23:0) after 1m0s
[16:33:25.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:23:0)
[16:33:25.051] Timer tick, asking Buildbucket for the build status
[16:33:25.094] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:25.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:24:0) after 1m0s
[16:34:25.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:24:0)
[16:34:25.111] Timer tick, asking Buildbucket for the build status
[16:34:25.156] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:25.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:25:0) after 1m0s
[16:35:25.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:25:0)
[16:35:25.183] Timer tick, asking Buildbucket for the build status
[16:35:25.259] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:25.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:26:0) after 1m0s
[16:36:25.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:26:0)
[16:36:25.273] Timer tick, asking Buildbucket for the build status
[16:36:25.314] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:25.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:27:0) after 1m0s
[16:37:25.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:27:0)
[16:37:25.336] Timer tick, asking Buildbucket for the build status
[16:37:25.369] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:37:25.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:28:0) after 1m0s
[16:38:25.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:28:0)
[16:38:25.429] Timer tick, asking Buildbucket for the build status
[16:38:25.529] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:25.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:29:0) after 1m0s
[16:39:25.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:29:0)
[16:39:25.543] Timer tick, asking Buildbucket for the build status
[16:39:25.575] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:25.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:30:0) after 1m0s
[16:40:25.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:30:0)
[16:40:25.591] Timer tick, asking Buildbucket for the build status
[16:40:25.665] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:25.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:31:0) after 1m0s
[16:41:25.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:31:0)
[16:41:25.696] Timer tick, asking Buildbucket for the build status
[16:41:25.782] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:25.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:32:0) after 1m0s
[16:42:25.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:32:0)
[16:42:25.803] Timer tick, asking Buildbucket for the build status
[16:42:25.843] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:25.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:33:0) after 1m0s
[16:43:25.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:33:0)
[16:43:25.861] Timer tick, asking Buildbucket for the build status
[16:43:26.012] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:43:26.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:34:0) after 1m0s
[16:44:26.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:34:0)
[16:44:26.038] Timer tick, asking Buildbucket for the build status
[16:44:26.108] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:26.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:35:0) after 1m0s
[16:45:26.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:35:0)
[16:45:26.131] Timer tick, asking Buildbucket for the build status
[16:45:26.165] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:26.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:36:0) after 1m0s
[16:46:26.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:36:0)
[16:46:26.186] Timer tick, asking Buildbucket for the build status
[16:46:26.257] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:26.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:37:0) after 1m0s
[16:47:26.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:37:0)
[16:47:26.273] Timer tick, asking Buildbucket for the build status
[16:47:26.363] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:26.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:38:0) after 1m0s
[16:48:26.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:38:0)
[16:48:26.381] Timer tick, asking Buildbucket for the build status
[16:48:26.460] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:26.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:39:0) after 1m0s
[16:49:26.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:39:0)
[16:49:26.478] Timer tick, asking Buildbucket for the build status
[16:49:26.555] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:26.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:40:0) after 1m0s
[16:50:26.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:40:0)
[16:50:26.573] Timer tick, asking Buildbucket for the build status
[16:50:26.628] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:50:26.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:41:0) after 1m0s
[16:51:26.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:41:0)
[16:51:26.654] Timer tick, asking Buildbucket for the build status
[16:51:26.757] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:51:26.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:42:0) after 1m0s
[16:52:26.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:42:0)
[16:52:26.774] Timer tick, asking Buildbucket for the build status
[16:52:26.815] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:26.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:43:0) after 1m0s
[16:53:26.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:43:0)
[16:53:26.828] Timer tick, asking Buildbucket for the build status
[16:53:26.872] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:26.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:44:0) after 1m0s
[16:54:26.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:44:0)
[16:54:26.890] Timer tick, asking Buildbucket for the build status
[16:54:27.067] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:27.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:45:0) after 1m0s
[16:55:27.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:45:0)
[16:55:27.110] Timer tick, asking Buildbucket for the build status
[16:55:27.142] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:27.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:46:0) after 1m0s
[16:56:27.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:46:0)
[16:56:27.160] Timer tick, asking Buildbucket for the build status
[16:56:27.264] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:27.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:47:0) after 1m0s
[16:57:27.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:47:0)
[16:57:27.280] Timer tick, asking Buildbucket for the build status
[16:57:27.347] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:27.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:48:0) after 1m0s
[16:58:27.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:48:0)
[16:58:27.366] Timer tick, asking Buildbucket for the build status
[16:58:27.438] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:58:27.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:49:0) after 1m0s
[16:59:27.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:49:0)
[16:59:27.454] Timer tick, asking Buildbucket for the build status
[16:59:27.508] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:27.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:50:0) after 1m0s
[17:00:27.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:50:0)
[17:00:27.527] Timer tick, asking Buildbucket for the build status
[17:00:27.581] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:00:27.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:51:0) after 1m0s
[17:01:27.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:51:0)
[17:01:27.603] Timer tick, asking Buildbucket for the build status
[17:01:27.773] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:01:27.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:52:0) after 1m0s
[17:02:27.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:52:0)
[17:02:27.812] Timer tick, asking Buildbucket for the build status
[17:02:27.890] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:02:27.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:53:0) after 1m0s
[17:03:27.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:53:0)
[17:03:27.924] Timer tick, asking Buildbucket for the build status
[17:03:28.107] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:03:28.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:54:0) after 1m0s
[17:04:28.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:54:0)
[17:04:28.199] Timer tick, asking Buildbucket for the build status
[17:04:28.249] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:04:28.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:55:0) after 1m0s
[17:05:28.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:55:0)
[17:05:28.266] Timer tick, asking Buildbucket for the build status
[17:05:28.344] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:05:28.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:56:0) after 1m0s
[17:06:28.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:56:0)
[17:06:28.360] Timer tick, asking Buildbucket for the build status
[17:06:28.413] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:06:28.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:57:0) after 1m0s
[17:07:28.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:57:0)
[17:07:28.431] Timer tick, asking Buildbucket for the build status
[17:07:28.508] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:07:28.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:58:0) after 1m0s
[17:08:28.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:58:0)
[17:08:28.528] Timer tick, asking Buildbucket for the build status
[17:08:28.583] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:08:28.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:59:0) after 1m0s
[17:09:28.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:59:0)
[17:09:28.602] Timer tick, asking Buildbucket for the build status
[17:09:28.739] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:09:28.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:60:0) after 1m0s
[17:10:28.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:60:0)
[17:10:28.756] Timer tick, asking Buildbucket for the build status
[17:10:28.805] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:10:28.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:61:0) after 1m0s
[17:11:28.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:61:0)
[17:11:28.826] Timer tick, asking Buildbucket for the build status
[17:11:28.971] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:11:28.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:62:0) after 1m0s
[17:12:28.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:62:0)
[17:12:28.990] Timer tick, asking Buildbucket for the build status
[17:12:29.057] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:12:29.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:63:0) after 1m0s
[17:13:29.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:63:0)
[17:13:29.074] Timer tick, asking Buildbucket for the build status
[17:13:29.154] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:13:29.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:64:0) after 1m0s
[17:14:29.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:64:0)
[17:14:29.176] Timer tick, asking Buildbucket for the build status
[17:14:29.232] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:14:29.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:65:0) after 1m0s
[17:15:29.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:65:0)
[17:15:29.252] Timer tick, asking Buildbucket for the build status
[17:15:29.359] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:15:29.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:66:0) after 1m0s
[17:16:29.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:66:0)
[17:16:29.378] Timer tick, asking Buildbucket for the build status
[17:16:29.556] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:16:29.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:67:0) after 1m0s
[17:17:29.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:67:0)
[17:17:29.602] Timer tick, asking Buildbucket for the build status
[17:17:29.675] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:17:29.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:68:0) after 1m0s
[17:18:29.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:68:0)
[17:18:29.697] Timer tick, asking Buildbucket for the build status
[17:18:29.730] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:18:29.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:69:0) after 1m0s
[17:19:29.746] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:69:0)
[17:19:29.746] Timer tick, asking Buildbucket for the build status
[17:19:29.808] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:19:29.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:70:0) after 1m0s
[17:20:29.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:70:0)
[17:20:29.825] Timer tick, asking Buildbucket for the build status
[17:20:29.871] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:20:29.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:71:0) after 1m0s
[17:21:29.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:71:0)
[17:21:29.889] Timer tick, asking Buildbucket for the build status
[17:21:29.960] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:29.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:72:0) after 1m0s
[17:22:29.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:72:0)
[17:22:29.977] Timer tick, asking Buildbucket for the build status
[17:22:30.036] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:30.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:73:0) after 1m0s
[17:23:30.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:73:0)
[17:23:30.055] Timer tick, asking Buildbucket for the build status
[17:23:30.175] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:30.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:74:0) after 1m0s
[17:24:30.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:74:0)
[17:24:30.192] Timer tick, asking Buildbucket for the build status
[17:24:30.238] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:30.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:75:0) after 1m0s
[17:25:30.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:75:0)
[17:25:30.264] Timer tick, asking Buildbucket for the build status
[17:25:30.320] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:30.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:76:0) after 1m0s
[17:26:30.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:76:0)
[17:26:30.339] Timer tick, asking Buildbucket for the build status
[17:26:30.514] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:30.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:77:0) after 1m0s
[17:27:30.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:77:0)
[17:27:30.538] Timer tick, asking Buildbucket for the build status
[17:27:30.683] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:27:30.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:78:0) after 1m0s
[17:28:30.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:78:0)
[17:28:30.706] Timer tick, asking Buildbucket for the build status
[17:28:30.860] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:28:30.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:79:0) after 1m0s
[17:29:30.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:79:0)
[17:29:30.893] Timer tick, asking Buildbucket for the build status
[17:29:30.952] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:29:30.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:80:0) after 1m0s
[17:30:31.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:80:0)
[17:30:31.018] Timer tick, asking Buildbucket for the build status
[17:30:31.082] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:31.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:81:0) after 1m0s
[17:31:31.100] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:81:0)
[17:31:31.100] Timer tick, asking Buildbucket for the build status
[17:31:31.158] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:31.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:82:0) after 1m0s
[17:32:31.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:82:0)
[17:32:31.174] Timer tick, asking Buildbucket for the build status
[17:32:31.272] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:31.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:83:0) after 1m0s
[17:33:31.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:83:0)
[17:33:31.291] Timer tick, asking Buildbucket for the build status
[17:33:31.366] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:31.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:84:0) after 1m0s
[17:34:31.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:84:0)
[17:34:31.386] Timer tick, asking Buildbucket for the build status
[17:34:31.516] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:31.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:85:0) after 1m0s
[17:35:31.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:85:0)
[17:35:31.535] Timer tick, asking Buildbucket for the build status
[17:35:31.598] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:31.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:86:0) after 1m0s
[17:36:31.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:86:0)
[17:36:31.642] Timer tick, asking Buildbucket for the build status
[17:36:31.677] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:31.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:87:0) after 1m0s
[17:37:31.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:87:0)
[17:37:31.695] Timer tick, asking Buildbucket for the build status
[17:37:31.774] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:31.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:88:0) after 1m0s
[17:38:31.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:88:0)
[17:38:31.791] Timer tick, asking Buildbucket for the build status
[17:38:31.827] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:31.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:89:0) after 1m0s
[17:39:31.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:89:0)
[17:39:31.844] Timer tick, asking Buildbucket for the build status
[17:39:32.021] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:32.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:90:0) after 1m0s
[17:40:32.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:90:0)
[17:40:32.125] Timer tick, asking Buildbucket for the build status
[17:40:32.164] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:32.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:91:0) after 1m0s
[17:41:32.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:91:0)
[17:41:32.182] Timer tick, asking Buildbucket for the build status
[17:41:32.226] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:32.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:92:0) after 1m0s
[17:42:32.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:92:0)
[17:42:32.286] Timer tick, asking Buildbucket for the build status
[17:42:32.371] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:32.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:93:0) after 1m0s
[17:43:32.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:93:0)
[17:43:32.390] Timer tick, asking Buildbucket for the build status
[17:43:32.589] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:32.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:94:0) after 1m0s
[17:44:32.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:94:0)
[17:44:32.608] Timer tick, asking Buildbucket for the build status
[17:44:32.680] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:44:32.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:95:0) after 1m0s
[17:45:32.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:95:0)
[17:45:32.697] Timer tick, asking Buildbucket for the build status
[17:45:32.728] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:32.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:96:0) after 1m0s
[17:46:32.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:96:0)
[17:46:32.757] Timer tick, asking Buildbucket for the build status
[17:46:32.929] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:32.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:97:0) after 1m0s
[17:47:33.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:97:0)
[17:47:33.298] Timer tick, asking Buildbucket for the build status
[17:47:33.365] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:33.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:98:0) after 1m0s
[17:48:33.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:98:0)
[17:48:33.382] Timer tick, asking Buildbucket for the build status
[17:48:33.650] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:33.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:99:0) after 1m0s
[17:49:33.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:99:0)
[17:49:33.670] Timer tick, asking Buildbucket for the build status
[17:49:33.728] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:33.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:100:0) after 1m0s
[17:50:33.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:100:0)
[17:50:33.772] Timer tick, asking Buildbucket for the build status
[17:50:33.855] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:33.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:101:0) after 1m0s
[17:51:33.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:101:0)
[17:51:33.871] Timer tick, asking Buildbucket for the build status
[17:51:33.969] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:51:33.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:102:0) after 1m0s
[17:52:33.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:102:0)
[17:52:33.995] Timer tick, asking Buildbucket for the build status
[17:52:34.075] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:52:34.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:103:0) after 1m0s
[17:53:34.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:103:0)
[17:53:34.097] Timer tick, asking Buildbucket for the build status
[17:53:34.160] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:34.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:104:0) after 1m0s
[17:54:34.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:104:0)
[17:54:34.178] Timer tick, asking Buildbucket for the build status
[17:54:34.249] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:34.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:105:0) after 1m0s
[17:55:34.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:105:0)
[17:55:34.268] Timer tick, asking Buildbucket for the build status
[17:55:34.421] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:34.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:106:0) after 1m0s
[17:56:34.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:106:0)
[17:56:34.438] Timer tick, asking Buildbucket for the build status
[17:56:34.520] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:34.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:107:0) after 1m0s
[17:57:34.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:107:0)
[17:57:34.536] Timer tick, asking Buildbucket for the build status
[17:57:34.587] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:34.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:108:0) after 1m0s
[17:58:34.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:108:0)
[17:58:34.605] Timer tick, asking Buildbucket for the build status
[17:58:34.642] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:58:34.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:109:0) after 1m0s
[17:59:34.660] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:109:0)
[17:59:34.660] Timer tick, asking Buildbucket for the build status
[17:59:34.746] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:34.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:110:0) after 1m0s
[18:00:34.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:110:0)
[18:00:34.784] Timer tick, asking Buildbucket for the build status
[18:00:34.862] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:34.862] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:111:0) after 1m0s
[18:01:34.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:111:0)
[18:01:34.881] Timer tick, asking Buildbucket for the build status
[18:01:34.926] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:34.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:112:0) after 1m0s
[18:02:35.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:112:0)
[18:02:35.006] Timer tick, asking Buildbucket for the build status
[18:02:35.084] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:35.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:113:0) after 1m0s
[18:03:35.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:113:0)
[18:03:35.106] Timer tick, asking Buildbucket for the build status
[18:03:35.159] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:35.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:114:0) after 1m0s
[18:04:35.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:114:0)
[18:04:35.180] Timer tick, asking Buildbucket for the build status
[18:04:35.266] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:35.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:115:0) after 1m0s
[18:05:35.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:115:0)
[18:05:35.332] Timer tick, asking Buildbucket for the build status
[18:05:35.386] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:35.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:116:0) after 1m0s
[18:06:35.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:116:0)
[18:06:35.403] Timer tick, asking Buildbucket for the build status
[18:06:35.596] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:35.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:117:0) after 1m0s
[18:07:35.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:117:0)
[18:07:35.617] Timer tick, asking Buildbucket for the build status
[18:07:35.674] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:35.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:118:0) after 1m0s
[18:08:35.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:118:0)
[18:08:35.696] Timer tick, asking Buildbucket for the build status
[18:08:35.786] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:35.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:119:0) after 1m0s
[18:09:35.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:119:0)
[18:09:35.805] Timer tick, asking Buildbucket for the build status
[18:09:35.868] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:35.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:120:0) after 1m0s
[18:10:35.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:120:0)
[18:10:35.900] Timer tick, asking Buildbucket for the build status
[18:10:35.937] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:35.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:121:0) after 1m0s
[18:11:35.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:121:0)
[18:11:35.958] Timer tick, asking Buildbucket for the build status
[18:11:36.138] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:36.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:122:0) after 1m0s
[18:12:36.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:122:0)
[18:12:36.172] Timer tick, asking Buildbucket for the build status
[18:12:36.230] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:36.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:123:0) after 1m0s
[18:13:36.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:123:0)
[18:13:36.251] Timer tick, asking Buildbucket for the build status
[18:13:36.293] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:36.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:124:0) after 1m0s
[18:14:36.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:124:0)
[18:14:36.368] Timer tick, asking Buildbucket for the build status
[18:14:36.475] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:36.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:125:0) after 1m0s
[18:15:36.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:125:0)
[18:15:36.498] Timer tick, asking Buildbucket for the build status
[18:15:36.601] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:15:36.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:126:0) after 1m0s
[18:16:36.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:126:0)
[18:16:36.631] Timer tick, asking Buildbucket for the build status
[18:16:36.886] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:16:36.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:127:0) after 1m0s
[18:17:36.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:127:0)
[18:17:36.926] Timer tick, asking Buildbucket for the build status
[18:17:36.977] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:36.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:128:0) after 1m0s
[18:18:36.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:128:0)
[18:18:36.994] Timer tick, asking Buildbucket for the build status
[18:18:37.048] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:37.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:129:0) after 1m0s
[18:19:37.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:129:0)
[18:19:37.084] Timer tick, asking Buildbucket for the build status
[18:19:37.154] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:37.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:130:0) after 1m0s
[18:20:37.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:130:0)
[18:20:37.172] Timer tick, asking Buildbucket for the build status
[18:20:37.370] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:37.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:131:0) after 1m0s
[18:21:37.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:131:0)
[18:21:37.390] Timer tick, asking Buildbucket for the build status
[18:21:37.474] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:37.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:132:0) after 1m0s
[18:22:37.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:132:0)
[18:22:37.493] Timer tick, asking Buildbucket for the build status
[18:22:37.559] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:37.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:133:0) after 1m0s
[18:23:37.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:133:0)
[18:23:37.580] Timer tick, asking Buildbucket for the build status
[18:23:37.733] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:37.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:134:0) after 1m0s
[18:24:37.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:134:0)
[18:24:37.752] Timer tick, asking Buildbucket for the build status
[18:24:37.892] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:37.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:135:0) after 1m0s
[18:25:37.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:135:0)
[18:25:37.915] Timer tick, asking Buildbucket for the build status
[18:25:37.992] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:37.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:136:0) after 1m0s
[18:26:38.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:136:0)
[18:26:38.020] Timer tick, asking Buildbucket for the build status
[18:26:38.270] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:38.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:137:0) after 1m0s
[18:27:38.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:137:0)
[18:27:38.292] Timer tick, asking Buildbucket for the build status
[18:27:38.362] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:38.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:138:0) after 1m0s
[18:28:38.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:138:0)
[18:28:38.394] Timer tick, asking Buildbucket for the build status
[18:28:38.562] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:38.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:139:0) after 1m0s
[18:29:38.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:139:0)
[18:29:38.589] Timer tick, asking Buildbucket for the build status
[18:29:38.681] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:38.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:140:0) after 1m0s
[18:30:38.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:140:0)
[18:30:38.716] Timer tick, asking Buildbucket for the build status
[18:30:38.796] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:38.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:141:0) after 1m0s
[18:31:38.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:141:0)
[18:31:38.881] Timer tick, asking Buildbucket for the build status
[18:31:38.924] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:38.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:142:0) after 1m0s
[18:32:38.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:142:0)
[18:32:38.945] Timer tick, asking Buildbucket for the build status
[18:32:39.009] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:39.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:143:0) after 1m0s
[18:33:39.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:143:0)
[18:33:39.031] Timer tick, asking Buildbucket for the build status
[18:33:39.098] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:39.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:144:0) after 1m0s
[18:34:39.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:144:0)
[18:34:39.121] Timer tick, asking Buildbucket for the build status
[18:34:39.226] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:39.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:145:0) after 1m0s
[18:35:39.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:145:0)
[18:35:39.245] Timer tick, asking Buildbucket for the build status
[18:35:39.309] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:39.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:146:0) after 1m0s
[18:36:39.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:146:0)
[18:36:39.332] Timer tick, asking Buildbucket for the build status
[18:36:39.431] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:39.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:147:0) after 1m0s
[18:37:39.485] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:147:0)
[18:37:39.485] Timer tick, asking Buildbucket for the build status
[18:37:39.539] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:39.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:148:0) after 1m0s
[18:38:39.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:148:0)
[18:38:39.617] Timer tick, asking Buildbucket for the build status
[18:38:39.692] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:39.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:149:0) after 1m0s
[18:39:39.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:149:0)
[18:39:39.711] Timer tick, asking Buildbucket for the build status
[18:39:39.752] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:39.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:150:0) after 1m0s
[18:40:39.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:150:0)
[18:40:39.775] Timer tick, asking Buildbucket for the build status
[18:40:40.176] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:40.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:151:0) after 1m0s
[18:41:40.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:151:0)
[18:41:40.207] Timer tick, asking Buildbucket for the build status
[18:41:40.251] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:40.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:152:0) after 1m0s
[18:42:40.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:152:0)
[18:42:40.270] Timer tick, asking Buildbucket for the build status
[18:42:40.342] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:40.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:153:0) after 1m0s
[18:43:40.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:153:0)
[18:43:40.365] Timer tick, asking Buildbucket for the build status
[18:43:40.411] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:40.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:154:0) after 1m0s
[18:44:40.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:154:0)
[18:44:40.431] Timer tick, asking Buildbucket for the build status
[18:44:40.493] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:40.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:155:0) after 1m0s
[18:45:40.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:155:0)
[18:45:40.514] Timer tick, asking Buildbucket for the build status
[18:45:40.610] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:40.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:156:0) after 1m0s
[18:46:40.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:156:0)
[18:46:40.631] Timer tick, asking Buildbucket for the build status
[18:46:40.717] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:40.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:157:0) after 1m0s
[18:47:40.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:157:0)
[18:47:40.756] Timer tick, asking Buildbucket for the build status
[18:47:40.815] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:40.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:158:0) after 1m0s
[18:48:40.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:158:0)
[18:48:40.835] Timer tick, asking Buildbucket for the build status
[18:48:40.879] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:40.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:159:0) after 1m0s
[18:49:40.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:159:0)
[18:49:40.899] Timer tick, asking Buildbucket for the build status
[18:49:40.995] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:40.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:160:0) after 1m0s
[18:50:41.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:160:0)
[18:50:41.079] Timer tick, asking Buildbucket for the build status
[18:50:41.152] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:41.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:161:0) after 1m0s
[18:51:41.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:161:0)
[18:51:41.217] Timer tick, asking Buildbucket for the build status
[18:51:41.271] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:41.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:162:0) after 1m0s
[18:52:41.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:162:0)
[18:52:41.293] Timer tick, asking Buildbucket for the build status
[18:52:41.440] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:41.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:163:0) after 1m0s
[18:53:41.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:163:0)
[18:53:41.463] Timer tick, asking Buildbucket for the build status
[18:53:41.519] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:41.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:164:0) after 1m0s
[18:54:41.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:164:0)
[18:54:41.550] Timer tick, asking Buildbucket for the build status
[18:54:41.590] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:41.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:165:0) after 1m0s
[18:55:41.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:165:0)
[18:55:41.640] Timer tick, asking Buildbucket for the build status
[18:55:41.711] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:41.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:166:0) after 1m0s
[18:56:41.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:166:0)
[18:56:41.790] Timer tick, asking Buildbucket for the build status
[18:56:41.850] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:41.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:167:0) after 1m0s
[18:57:41.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:167:0)
[18:57:41.900] Timer tick, asking Buildbucket for the build status
[18:57:42.015] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:42.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:168:0) after 1m0s
[18:58:42.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:168:0)
[18:58:42.036] Timer tick, asking Buildbucket for the build status
[18:58:42.112] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:42.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:169:0) after 1m0s
[18:59:42.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:169:0)
[18:59:42.133] Timer tick, asking Buildbucket for the build status
[18:59:42.211] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:42.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:170:0) after 1m0s
[19:00:42.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:170:0)
[19:00:42.229] Timer tick, asking Buildbucket for the build status
[19:00:42.291] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:42.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:171:0) after 1m0s
[19:01:42.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:171:0)
[19:01:42.314] Timer tick, asking Buildbucket for the build status
[19:01:42.366] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:42.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:172:0) after 1m0s
[19:02:42.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:172:0)
[19:02:42.386] Timer tick, asking Buildbucket for the build status
[19:02:42.458] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:42.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:173:0) after 1m0s
[19:03:42.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:173:0)
[19:03:42.483] Timer tick, asking Buildbucket for the build status
[19:03:42.586] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:42.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:174:0) after 1m0s
[19:04:42.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:174:0)
[19:04:42.605] Timer tick, asking Buildbucket for the build status
[19:04:42.708] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:42.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:175:0) after 1m0s
[19:05:42.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:175:0)
[19:05:42.726] Timer tick, asking Buildbucket for the build status
[19:05:42.773] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:42.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:176:0) after 1m0s
[19:06:42.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:176:0)
[19:06:42.795] Timer tick, asking Buildbucket for the build status
[19:06:42.863] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:42.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:177:0) after 1m0s
[19:07:42.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:177:0)
[19:07:42.982] Timer tick, asking Buildbucket for the build status
[19:07:43.031] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:43.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:178:0) after 1m0s
[19:08:43.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:178:0)
[19:08:43.111] Timer tick, asking Buildbucket for the build status
[19:08:43.158] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:43.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:179:0) after 1m0s
[19:09:43.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:179:0)
[19:09:43.237] Timer tick, asking Buildbucket for the build status
[19:09:43.345] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:43.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:180:0) after 1m0s
[19:10:43.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:180:0)
[19:10:43.460] Timer tick, asking Buildbucket for the build status
[19:10:43.503] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:43.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:181:0) after 1m0s
[19:11:43.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:181:0)
[19:11:43.533] Timer tick, asking Buildbucket for the build status
[19:11:43.602] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:43.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:182:0) after 1m0s
[19:12:43.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:182:0)
[19:12:43.714] Timer tick, asking Buildbucket for the build status
[19:12:43.810] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:43.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:183:0) after 1m0s
[19:13:43.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:183:0)
[19:13:43.855] Timer tick, asking Buildbucket for the build status
[19:13:43.955] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:43.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:184:0) after 1m0s
[19:14:43.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:184:0)
[19:14:43.974] Timer tick, asking Buildbucket for the build status
[19:14:44.089] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:44.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:185:0) after 1m0s
[19:15:44.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:185:0)
[19:15:44.128] Timer tick, asking Buildbucket for the build status
[19:15:44.173] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:44.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:186:0) after 1m0s
[19:16:44.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:186:0)
[19:16:44.192] Timer tick, asking Buildbucket for the build status
[19:16:44.434] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:44.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:187:0) after 1m0s
[19:17:44.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:187:0)
[19:17:44.454] Timer tick, asking Buildbucket for the build status
[19:17:44.495] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:44.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:188:0) after 1m0s
[19:18:44.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:188:0)
[19:18:44.514] Timer tick, asking Buildbucket for the build status
[19:18:44.645] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:44.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:189:0) after 1m0s
[19:19:44.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:189:0)
[19:19:44.669] Timer tick, asking Buildbucket for the build status
[19:19:44.791] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:44.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:190:0) after 1m0s
[19:20:44.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:190:0)
[19:20:44.826] Timer tick, asking Buildbucket for the build status
[19:20:45.146] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:45.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:191:0) after 1m0s
[19:21:45.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:191:0)
[19:21:45.166] Timer tick, asking Buildbucket for the build status
[19:21:45.362] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:45.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:192:0) after 1m0s
[19:22:45.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:192:0)
[19:22:45.382] Timer tick, asking Buildbucket for the build status
[19:22:45.443] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:45.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:193:0) after 1m0s
[19:23:45.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:193:0)
[19:23:45.464] Timer tick, asking Buildbucket for the build status
[19:23:45.507] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:45.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:194:0) after 1m0s
[19:24:45.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:194:0)
[19:24:45.537] Timer tick, asking Buildbucket for the build status
[19:24:45.596] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:45.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:195:0) after 1m0s
[19:25:45.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:195:0)
[19:25:45.651] Timer tick, asking Buildbucket for the build status
[19:25:45.702] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:45.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:196:0) after 1m0s
[19:26:45.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:196:0)
[19:26:45.726] Timer tick, asking Buildbucket for the build status
[19:26:45.789] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:45.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:197:0) after 1m0s
[19:27:45.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:197:0)
[19:27:45.812] Timer tick, asking Buildbucket for the build status
[19:27:45.935] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:45.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:198:0) after 1m0s
[19:28:45.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:198:0)
[19:28:45.954] Timer tick, asking Buildbucket for the build status
[19:28:46.057] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:46.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:199:0) after 1m0s
[19:29:46.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:199:0)
[19:29:46.078] Timer tick, asking Buildbucket for the build status
[19:29:46.145] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:46.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:200:0) after 1m0s
[19:30:46.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:200:0)
[19:30:46.167] Timer tick, asking Buildbucket for the build status
[19:30:46.232] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:46.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:201:0) after 1m0s
[19:31:46.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:201:0)
[19:31:46.286] Timer tick, asking Buildbucket for the build status
[19:31:46.335] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:46.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:202:0) after 1m0s
[19:32:46.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:202:0)
[19:32:46.354] Timer tick, asking Buildbucket for the build status
[19:32:46.540] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:46.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:203:0) after 1m0s
[19:33:46.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:203:0)
[19:33:46.560] Timer tick, asking Buildbucket for the build status
[19:33:46.598] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:46.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:204:0) after 1m0s
[19:34:46.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:204:0)
[19:34:46.626] Timer tick, asking Buildbucket for the build status
[19:34:46.678] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:46.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:205:0) after 1m0s
[19:35:46.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:205:0)
[19:35:46.718] Timer tick, asking Buildbucket for the build status
[19:35:46.812] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:46.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:206:0) after 1m0s
[19:36:46.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:206:0)
[19:36:46.833] Timer tick, asking Buildbucket for the build status
[19:36:46.881] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:46.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:207:0) after 1m0s
[19:37:46.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:207:0)
[19:37:46.904] Timer tick, asking Buildbucket for the build status
[19:37:47.128] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:47.128] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:208:0) after 1m0s
[19:38:47.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:208:0)
[19:38:47.152] Timer tick, asking Buildbucket for the build status
[19:38:47.218] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:47.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:209:0) after 1m0s
[19:39:47.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:209:0)
[19:39:47.261] Timer tick, asking Buildbucket for the build status
[19:39:47.306] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:47.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:210:0) after 1m0s
[19:40:47.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:210:0)
[19:40:47.326] Timer tick, asking Buildbucket for the build status
[19:40:47.448] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:47.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:211:0) after 1m0s
[19:41:47.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:211:0)
[19:41:47.471] Timer tick, asking Buildbucket for the build status
[19:41:47.557] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:47.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:212:0) after 1m0s
[19:42:47.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:212:0)
[19:42:47.578] Timer tick, asking Buildbucket for the build status
[19:42:47.672] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:47.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:213:0) after 1m0s
[19:43:47.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:213:0)
[19:43:47.696] Timer tick, asking Buildbucket for the build status
[19:43:47.840] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:47.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:214:0) after 1m0s
[19:44:47.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:214:0)
[19:44:47.877] Timer tick, asking Buildbucket for the build status
[19:44:47.934] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:47.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:215:0) after 1m0s
[19:45:47.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:215:0)
[19:45:47.955] Timer tick, asking Buildbucket for the build status
[19:45:48.027] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:48.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:216:0) after 1m0s
[19:46:48.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:216:0)
[19:46:48.062] Timer tick, asking Buildbucket for the build status
[19:46:48.171] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:48.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:217:0) after 1m0s
[19:47:48.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:217:0)
[19:47:48.208] Timer tick, asking Buildbucket for the build status
[19:47:48.267] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:48.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:218:0) after 1m0s
[19:48:48.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:218:0)
[19:48:48.293] Timer tick, asking Buildbucket for the build status
[19:48:48.401] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:48.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:219:0) after 1m0s
[19:49:48.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:219:0)
[19:49:48.436] Timer tick, asking Buildbucket for the build status
[19:49:48.521] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:48.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:220:0) after 1m0s
[19:50:48.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:220:0)
[19:50:48.542] Timer tick, asking Buildbucket for the build status
[19:50:48.585] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:48.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:221:0) after 1m0s
[19:51:48.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:221:0)
[19:51:48.625] Timer tick, asking Buildbucket for the build status
[19:51:48.689] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:48.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:222:0) after 1m0s
[19:52:48.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:222:0)
[19:52:48.708] Timer tick, asking Buildbucket for the build status
[19:52:48.925] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:48.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:223:0) after 1m0s
[19:53:48.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:223:0)
[19:53:48.945] Timer tick, asking Buildbucket for the build status
[19:53:49.143] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:49.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:224:0) after 1m0s
[19:54:49.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:224:0)
[19:54:49.163] Timer tick, asking Buildbucket for the build status
[19:54:49.202] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:49.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:225:0) after 1m0s
[19:55:49.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:225:0)
[19:55:49.237] Timer tick, asking Buildbucket for the build status
[19:55:49.294] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:49.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:226:0) after 1m0s
[19:56:49.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:226:0)
[19:56:49.323] Timer tick, asking Buildbucket for the build status
[19:56:49.430] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:49.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:227:0) after 1m0s
[19:57:49.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:227:0)
[19:57:49.691] Timer tick, asking Buildbucket for the build status
[19:57:49.762] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:49.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:228:0) after 1m0s
[19:58:49.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:228:0)
[19:58:49.870] Timer tick, asking Buildbucket for the build status
[19:58:49.902] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:49.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:229:0) after 1m0s
[19:59:49.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:229:0)
[19:59:49.945] Timer tick, asking Buildbucket for the build status
[19:59:50.046] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:50.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:230:0) after 1m0s
[20:00:50.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:230:0)
[20:00:50.119] Timer tick, asking Buildbucket for the build status
[20:00:50.237] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:50.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:231:0) after 1m0s
[20:01:50.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:231:0)
[20:01:50.260] Timer tick, asking Buildbucket for the build status
[20:01:50.328] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:50.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:232:0) after 1m0s
[20:02:50.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:232:0)
[20:02:50.350] Timer tick, asking Buildbucket for the build status
[20:02:50.383] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:50.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:233:0) after 1m0s
[20:03:50.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:233:0)
[20:03:50.456] Timer tick, asking Buildbucket for the build status
[20:03:50.929] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:50.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:234:0) after 1m0s
[20:04:50.953] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:234:0)
[20:04:50.953] Timer tick, asking Buildbucket for the build status
[20:04:51.001] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:51.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:235:0) after 1m0s
[20:05:51.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:235:0)
[20:05:51.038] Timer tick, asking Buildbucket for the build status
[20:05:51.117] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:51.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:236:0) after 1m0s
[20:06:51.139] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:236:0)
[20:06:51.139] Timer tick, asking Buildbucket for the build status
[20:06:51.199] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:51.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:237:0) after 1m0s
[20:07:51.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:237:0)
[20:07:51.224] Timer tick, asking Buildbucket for the build status
[20:07:51.275] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:51.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:238:0) after 1m0s
[20:08:51.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:238:0)
[20:08:51.309] Timer tick, asking Buildbucket for the build status
[20:08:51.366] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:51.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:239:0) after 1m0s
[20:09:51.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:239:0)
[20:09:51.435] Timer tick, asking Buildbucket for the build status
[20:09:51.491] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:51.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:240:0) after 1m0s
[20:10:51.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:240:0)
[20:10:51.526] Timer tick, asking Buildbucket for the build status
[20:10:51.564] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:51.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:241:0) after 1m0s
[20:11:51.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:241:0)
[20:11:51.584] Timer tick, asking Buildbucket for the build status
[20:11:51.649] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:51.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:242:0) after 1m0s
[20:12:51.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:242:0)
[20:12:51.672] Timer tick, asking Buildbucket for the build status
[20:12:51.701] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:51.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:243:0) after 1m0s
[20:13:51.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:243:0)
[20:13:51.731] Timer tick, asking Buildbucket for the build status
[20:13:51.812] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:51.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:244:0) after 1m0s
[20:14:51.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:244:0)
[20:14:51.834] Timer tick, asking Buildbucket for the build status
[20:14:51.933] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:51.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:245:0) after 1m0s
[20:15:51.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:245:0)
[20:15:51.968] Timer tick, asking Buildbucket for the build status
[20:15:52.048] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:52.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:246:0) after 1m0s
[20:16:52.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:246:0)
[20:16:52.075] Timer tick, asking Buildbucket for the build status
[20:16:52.133] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:52.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:247:0) after 1m0s
[20:17:52.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:247:0)
[20:17:52.157] Timer tick, asking Buildbucket for the build status
[20:17:52.225] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:52.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:248:0) after 1m0s
[20:18:52.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:248:0)
[20:18:52.246] Timer tick, asking Buildbucket for the build status
[20:18:52.294] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:52.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:249:0) after 1m0s
[20:19:52.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:249:0)
[20:19:52.316] Timer tick, asking Buildbucket for the build status
[20:19:52.410] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:52.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:250:0) after 1m0s
[20:20:52.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:250:0)
[20:20:52.448] Timer tick, asking Buildbucket for the build status
[20:20:52.536] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:52.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:251:0) after 1m0s
[20:21:52.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:251:0)
[20:21:52.557] Timer tick, asking Buildbucket for the build status
[20:21:52.631] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:52.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:252:0) after 1m0s
[20:22:52.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:252:0)
[20:22:52.702] Timer tick, asking Buildbucket for the build status
[20:22:52.736] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:52.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:253:0) after 1m0s
[20:23:52.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:253:0)
[20:23:52.762] Timer tick, asking Buildbucket for the build status
[20:23:52.884] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:52.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:254:0) after 1m0s
[20:24:52.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:254:0)
[20:24:52.911] Timer tick, asking Buildbucket for the build status
[20:24:52.954] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:52.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:255:0) after 1m0s
[20:25:52.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:255:0)
[20:25:52.978] Timer tick, asking Buildbucket for the build status
[20:25:53.038] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:53.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:256:0) after 1m0s
[20:26:53.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:256:0)
[20:26:53.067] Timer tick, asking Buildbucket for the build status
[20:26:53.172] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:53.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:257:0) after 1m0s
[20:27:53.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:257:0)
[20:27:53.197] Timer tick, asking Buildbucket for the build status
[20:27:53.302] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:53.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:258:0) after 1m0s
[20:28:53.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:258:0)
[20:28:53.332] Timer tick, asking Buildbucket for the build status
[20:28:53.426] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:53.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:259:0) after 1m0s
[20:29:53.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:259:0)
[20:29:53.451] Timer tick, asking Buildbucket for the build status
[20:29:53.537] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:53.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:260:0) after 1m0s
[20:30:53.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:260:0)
[20:30:53.557] Timer tick, asking Buildbucket for the build status
[20:30:53.681] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:53.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:261:0) after 1m0s
[20:31:53.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:261:0)
[20:31:53.699] Timer tick, asking Buildbucket for the build status
[20:31:53.806] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:53.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:262:0) after 1m0s
[20:32:53.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:262:0)
[20:32:53.834] Timer tick, asking Buildbucket for the build status
[20:32:53.877] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:53.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:263:0) after 1m0s
[20:33:53.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:263:0)
[20:33:53.898] Timer tick, asking Buildbucket for the build status
[20:33:53.961] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:53.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:264:0) after 1m0s
[20:34:54.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:264:0)
[20:34:54.000] Timer tick, asking Buildbucket for the build status
[20:34:54.062] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:54.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:265:0) after 1m0s
[20:35:54.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:265:0)
[20:35:54.086] Timer tick, asking Buildbucket for the build status
[20:35:54.153] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:54.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:266:0) after 1m0s
[20:36:54.179] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:266:0)
[20:36:54.179] Timer tick, asking Buildbucket for the build status
[20:36:54.243] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:54.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:267:0) after 1m0s
[20:37:54.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:267:0)
[20:37:54.268] Timer tick, asking Buildbucket for the build status
[20:37:54.314] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:54.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:268:0) after 1m0s
[20:38:54.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:268:0)
[20:38:54.345] Timer tick, asking Buildbucket for the build status
[20:38:54.468] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:54.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:269:0) after 1m0s
[20:39:54.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:269:0)
[20:39:54.511] Timer tick, asking Buildbucket for the build status
[20:39:54.602] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:54.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:270:0) after 1m0s
[20:40:54.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:270:0)
[20:40:54.621] Timer tick, asking Buildbucket for the build status
[20:40:54.671] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:54.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:271:0) after 1m0s
[20:41:54.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:271:0)
[20:41:54.707] Timer tick, asking Buildbucket for the build status
[20:41:54.754] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:54.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:272:0) after 1m0s
[20:42:54.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:272:0)
[20:42:54.786] Timer tick, asking Buildbucket for the build status
[20:42:54.856] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:54.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:273:0) after 1m0s
[20:43:54.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:273:0)
[20:43:54.927] Timer tick, asking Buildbucket for the build status
[20:43:54.957] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:54.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:274:0) after 1m0s
[20:44:54.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:274:0)
[20:44:54.979] Timer tick, asking Buildbucket for the build status
[20:44:55.045] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:55.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:275:0) after 1m0s
[20:45:55.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:275:0)
[20:45:55.096] Timer tick, asking Buildbucket for the build status
[20:45:55.138] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:55.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:276:0) after 1m0s
[20:46:55.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:276:0)
[20:46:55.176] Timer tick, asking Buildbucket for the build status
[20:46:55.249] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:55.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:277:0) after 1m0s
[20:47:55.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:277:0)
[20:47:55.320] Timer tick, asking Buildbucket for the build status
[20:47:55.368] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:55.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:278:0) after 1m0s
[20:48:55.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:278:0)
[20:48:55.393] Timer tick, asking Buildbucket for the build status
[20:48:55.449] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:55.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:279:0) after 1m0s
[20:49:55.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:279:0)
[20:49:55.474] Timer tick, asking Buildbucket for the build status
[20:49:55.574] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:55.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:280:0) after 1m0s
[20:50:55.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:280:0)
[20:50:55.599] Timer tick, asking Buildbucket for the build status
[20:50:55.645] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:55.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:281:0) after 1m0s
[20:51:55.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:281:0)
[20:51:55.670] Timer tick, asking Buildbucket for the build status
[20:51:55.721] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:55.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:282:0) after 1m0s
[20:52:55.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:282:0)
[20:52:55.742] Timer tick, asking Buildbucket for the build status
[20:52:55.821] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:55.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:283:0) after 1m0s
[20:53:55.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:283:0)
[20:53:55.846] Timer tick, asking Buildbucket for the build status
[20:53:55.933] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:55.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:284:0) after 1m0s
[20:54:55.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:284:0)
[20:54:55.971] Timer tick, asking Buildbucket for the build status
[20:54:56.011] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:56.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:285:0) after 1m0s
[20:55:56.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:285:0)
[20:55:56.035] Timer tick, asking Buildbucket for the build status
[20:55:56.153] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:56.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:286:0) after 1m0s
[20:56:56.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:286:0)
[20:56:56.202] Timer tick, asking Buildbucket for the build status
[20:56:56.309] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:56.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:287:0) after 1m0s
[20:57:56.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:287:0)
[20:57:56.335] Timer tick, asking Buildbucket for the build status
[20:57:56.404] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:56.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:288:0) after 1m0s
[20:58:56.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:288:0)
[20:58:56.442] Timer tick, asking Buildbucket for the build status
[20:58:56.536] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:56.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:289:0) after 1m0s
[20:59:56.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:289:0)
[20:59:56.562] Timer tick, asking Buildbucket for the build status
[20:59:56.683] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:56.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:290:0) after 1m0s
[21:00:56.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:290:0)
[21:00:56.707] Timer tick, asking Buildbucket for the build status
[21:00:56.782] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:56.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:291:0) after 1m0s
[21:01:56.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:291:0)
[21:01:56.809] Timer tick, asking Buildbucket for the build status
[21:01:57.378] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:57.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:292:0) after 1m0s
[21:02:57.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:292:0)
[21:02:57.431] Timer tick, asking Buildbucket for the build status
[21:02:57.468] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:57.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:293:0) after 1m0s
[21:03:57.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:293:0)
[21:03:57.506] Timer tick, asking Buildbucket for the build status
[21:03:57.565] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:57.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:294:0) after 1m0s
[21:04:57.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:294:0)
[21:04:57.619] Timer tick, asking Buildbucket for the build status
[21:04:57.659] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:57.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:295:0) after 1m0s
[21:05:57.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:295:0)
[21:05:57.683] Timer tick, asking Buildbucket for the build status
[21:05:57.809] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:57.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:296:0) after 1m0s
[21:06:57.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:296:0)
[21:06:57.837] Timer tick, asking Buildbucket for the build status
[21:06:57.873] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:57.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:297:0) after 1m0s
[21:07:57.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:297:0)
[21:07:57.904] Timer tick, asking Buildbucket for the build status
[21:07:57.962] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:57.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:298:0) after 1m0s
[21:08:57.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:298:0)
[21:08:57.986] Timer tick, asking Buildbucket for the build status
[21:08:58.118] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:58.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:299:0) after 1m0s
[21:09:58.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:299:0)
[21:09:58.143] Timer tick, asking Buildbucket for the build status
[21:09:58.231] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:58.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:300:0) after 1m0s
[21:10:58.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:300:0)
[21:10:58.281] Timer tick, asking Buildbucket for the build status
[21:10:58.365] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:58.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:301:0) after 1m0s
[21:11:58.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:301:0)
[21:11:58.396] Timer tick, asking Buildbucket for the build status
[21:11:58.469] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:58.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:302:0) after 1m0s
[21:12:58.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:302:0)
[21:12:58.497] Timer tick, asking Buildbucket for the build status
[21:12:58.598] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:58.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:303:0) after 1m0s
[21:13:58.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:303:0)
[21:13:58.620] Timer tick, asking Buildbucket for the build status
[21:13:58.656] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:58.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:304:0) after 1m0s
[21:14:58.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:304:0)
[21:14:58.685] Timer tick, asking Buildbucket for the build status
[21:14:58.822] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:58.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:305:0) after 1m0s
[21:15:58.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:305:0)
[21:15:58.849] Timer tick, asking Buildbucket for the build status
[21:15:58.883] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:58.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:306:0) after 1m0s
[21:16:58.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:306:0)
[21:16:58.920] Timer tick, asking Buildbucket for the build status
[21:16:58.993] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:58.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:307:0) after 1m0s
[21:17:59.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:307:0)
[21:17:59.015] Timer tick, asking Buildbucket for the build status
[21:17:59.081] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:59.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:308:0) after 1m0s
[21:18:59.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:308:0)
[21:18:59.111] Timer tick, asking Buildbucket for the build status
[21:18:59.169] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:59.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:309:0) after 1m0s
[21:19:59.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:309:0)
[21:19:59.211] Timer tick, asking Buildbucket for the build status
[21:19:59.248] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:59.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:310:0) after 1m0s
[21:20:59.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:310:0)
[21:20:59.278] Timer tick, asking Buildbucket for the build status
[21:20:59.314] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:59.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:311:0) after 1m0s
[21:21:59.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:311:0)
[21:21:59.355] Timer tick, asking Buildbucket for the build status
[21:21:59.512] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:59.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:312:0) after 1m0s
[21:22:59.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:312:0)
[21:22:59.543] Timer tick, asking Buildbucket for the build status
[21:22:59.609] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:59.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:313:0) after 1m0s
[21:23:59.641] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:313:0)
[21:23:59.641] Timer tick, asking Buildbucket for the build status
[21:23:59.692] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:59.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:314:0) after 1m0s
[21:24:59.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:314:0)
[21:24:59.737] Timer tick, asking Buildbucket for the build status
[21:24:59.848] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:59.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:315:0) after 1m0s
[21:25:59.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:315:0)
[21:25:59.873] Timer tick, asking Buildbucket for the build status
[21:25:59.925] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:59.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:316:0) after 1m0s
[21:26:59.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:316:0)
[21:26:59.950] Timer tick, asking Buildbucket for the build status
[21:27:00.034] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:00.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:317:0) after 1m0s
[21:28:00.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:317:0)
[21:28:00.069] Timer tick, asking Buildbucket for the build status
[21:28:00.146] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:00.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:318:0) after 1m0s
[21:29:00.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:318:0)
[21:29:00.189] Timer tick, asking Buildbucket for the build status
[21:29:00.313] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:00.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:319:0) after 1m0s
[21:30:00.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:319:0)
[21:30:00.341] Timer tick, asking Buildbucket for the build status
[21:30:00.485] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:00.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:320:0) after 1m0s
[21:31:00.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:320:0)
[21:31:00.509] Timer tick, asking Buildbucket for the build status
[21:31:00.608] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:00.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:321:0) after 1m0s
[21:32:00.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:321:0)
[21:32:00.634] Timer tick, asking Buildbucket for the build status
[21:32:00.690] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:00.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:322:0) after 1m0s
[21:33:00.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:322:0)
[21:33:00.713] Timer tick, asking Buildbucket for the build status
[21:33:00.805] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:00.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:323:0) after 1m0s
[21:34:00.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:323:0)
[21:34:00.840] Timer tick, asking Buildbucket for the build status
[21:34:00.919] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:00.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:324:0) after 1m0s
[21:35:00.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:324:0)
[21:35:00.947] Timer tick, asking Buildbucket for the build status
[21:35:01.005] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:01.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:325:0) after 1m0s
[21:36:01.040] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:325:0)
[21:36:01.040] Timer tick, asking Buildbucket for the build status
[21:36:01.105] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:01.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:326:0) after 1m0s
[21:37:01.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:326:0)
[21:37:01.130] Timer tick, asking Buildbucket for the build status
[21:37:01.414] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:01.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:327:0) after 1m0s
[21:38:01.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:327:0)
[21:38:01.444] Timer tick, asking Buildbucket for the build status
[21:38:01.488] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:01.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:328:0) after 1m0s
[21:39:01.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:328:0)
[21:39:01.514] Timer tick, asking Buildbucket for the build status
[21:39:01.624] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:01.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:329:0) after 1m0s
[21:40:01.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:329:0)
[21:40:01.653] Timer tick, asking Buildbucket for the build status
[21:40:01.698] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:01.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:330:0) after 1m0s
[21:41:01.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:330:0)
[21:41:01.727] Timer tick, asking Buildbucket for the build status
[21:41:01.907] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:01.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:331:0) after 1m0s
[21:42:01.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:331:0)
[21:42:01.973] Timer tick, asking Buildbucket for the build status
[21:42:02.038] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:02.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:332:0) after 1m0s
[21:43:02.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:332:0)
[21:43:02.062] Timer tick, asking Buildbucket for the build status
[21:43:02.127] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:02.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:333:0) after 1m0s
[21:44:02.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:333:0)
[21:44:02.153] Timer tick, asking Buildbucket for the build status
[21:44:02.208] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:02.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:334:0) after 1m0s
[21:45:02.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:334:0)
[21:45:02.343] Timer tick, asking Buildbucket for the build status
[21:45:03.605] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:03.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:335:0) after 1m0s
[21:46:03.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:335:0)
[21:46:03.631] Timer tick, asking Buildbucket for the build status
[21:46:03.709] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:03.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:336:0) after 1m0s
[21:47:03.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:336:0)
[21:47:03.735] Timer tick, asking Buildbucket for the build status
[21:47:03.847] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:03.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:337:0) after 1m0s
[21:48:03.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:337:0)
[21:48:03.875] Timer tick, asking Buildbucket for the build status
[21:48:04.005] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:04.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:338:0) after 1m0s
[21:49:04.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:338:0)
[21:49:04.026] Timer tick, asking Buildbucket for the build status
[21:49:04.081] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:04.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:339:0) after 1m0s
[21:50:04.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:339:0)
[21:50:04.107] Timer tick, asking Buildbucket for the build status
[21:50:04.218] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:04.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:340:0) after 1m0s
[21:51:04.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:340:0)
[21:51:04.244] Timer tick, asking Buildbucket for the build status
[21:51:04.285] Build 8923616159529032640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:04.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:341:0) after 1m0s
[21:51:24.178] Received PubSub notification, asking Buildbucket for the build status
[21:51:24.261] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:04.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:341:0)
[21:52:04.333] Timer tick, asking Buildbucket for the build status
[21:52:04.553] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:04.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:343:0) after 1m0s
[21:53:04.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:343:0)
[21:53:04.576] Timer tick, asking Buildbucket for the build status
[21:53:04.874] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:04.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:344:0) after 1m0s
[21:54:04.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:344:0)
[21:54:04.899] Timer tick, asking Buildbucket for the build status
[21:54:04.985] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:04.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:345:0) after 1m0s
[21:55:05.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:345:0)
[21:55:05.008] Timer tick, asking Buildbucket for the build status
[21:55:05.099] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:05.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:346:0) after 1m0s
[21:56:05.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:346:0)
[21:56:05.140] Timer tick, asking Buildbucket for the build status
[21:56:05.215] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:05.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:347:0) after 1m0s
[21:57:05.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:347:0)
[21:57:05.241] Timer tick, asking Buildbucket for the build status
[21:57:05.414] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:05.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:348:0) after 1m0s
[21:58:05.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:348:0)
[21:58:05.502] Timer tick, asking Buildbucket for the build status
[21:58:05.564] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:05.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:349:0) after 1m0s
[21:59:05.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:349:0)
[21:59:05.590] Timer tick, asking Buildbucket for the build status
[21:59:05.653] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:05.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:350:0) after 1m0s
[22:00:05.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:350:0)
[22:00:05.685] Timer tick, asking Buildbucket for the build status
[22:00:05.875] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:05.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:351:0) after 1m0s
[22:01:05.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:351:0)
[22:01:05.909] Timer tick, asking Buildbucket for the build status
[22:01:05.960] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:05.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:352:0) after 1m0s
[22:02:05.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:352:0)
[22:02:05.992] Timer tick, asking Buildbucket for the build status
[22:02:06.044] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:06.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:353:0) after 1m0s
[22:03:06.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:353:0)
[22:03:06.104] Timer tick, asking Buildbucket for the build status
[22:03:06.155] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:06.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:354:0) after 1m0s
[22:04:06.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:354:0)
[22:04:06.243] Timer tick, asking Buildbucket for the build status
[22:04:06.316] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:06.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:355:0) after 1m0s
[22:05:06.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:355:0)
[22:05:06.342] Timer tick, asking Buildbucket for the build status
[22:05:06.390] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:06.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:356:0) after 1m0s
[22:06:06.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:356:0)
[22:06:06.413] Timer tick, asking Buildbucket for the build status
[22:06:06.463] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:06.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:357:0) after 1m0s
[22:07:06.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:357:0)
[22:07:06.505] Timer tick, asking Buildbucket for the build status
[22:07:06.720] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:06.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:358:0) after 1m0s
[22:08:06.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:358:0)
[22:08:06.761] Timer tick, asking Buildbucket for the build status
[22:08:07.486] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:07.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:359:0) after 1m0s
[22:09:07.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:359:0)
[22:09:07.509] Timer tick, asking Buildbucket for the build status
[22:09:07.571] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:07.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:360:0) after 1m0s
[22:10:07.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:360:0)
[22:10:07.606] Timer tick, asking Buildbucket for the build status
[22:10:07.824] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:07.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:361:0) after 1m0s
[22:11:07.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:361:0)
[22:11:07.854] Timer tick, asking Buildbucket for the build status
[22:11:07.913] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:07.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:362:0) after 1m0s
[22:12:07.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:362:0)
[22:12:07.938] Timer tick, asking Buildbucket for the build status
[22:12:08.004] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:08.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:363:0) after 1m0s
[22:13:08.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:363:0)
[22:13:08.034] Timer tick, asking Buildbucket for the build status
[22:13:08.129] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:08.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:364:0) after 1m0s
[22:14:08.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:364:0)
[22:14:08.191] Timer tick, asking Buildbucket for the build status
[22:14:08.403] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:08.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:365:0) after 1m0s
[22:15:08.430] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:365:0)
[22:15:08.430] Timer tick, asking Buildbucket for the build status
[22:15:08.522] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:08.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:366:0) after 1m0s
[22:16:08.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:366:0)
[22:16:08.551] Timer tick, asking Buildbucket for the build status
[22:16:08.658] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:08.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:367:0) after 1m0s
[22:17:08.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:367:0)
[22:17:08.681] Timer tick, asking Buildbucket for the build status
[22:17:08.755] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:08.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:368:0) after 1m0s
[22:18:08.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:368:0)
[22:18:08.779] Timer tick, asking Buildbucket for the build status
[22:18:09.005] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:09.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:369:0) after 1m0s
[22:19:09.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:369:0)
[22:19:09.035] Timer tick, asking Buildbucket for the build status
[22:19:09.110] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:09.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:370:0) after 1m0s
[22:20:09.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:370:0)
[22:20:09.134] Timer tick, asking Buildbucket for the build status
[22:20:09.258] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:09.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:371:0) after 1m0s
[22:21:09.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:371:0)
[22:21:09.295] Timer tick, asking Buildbucket for the build status
[22:21:09.429] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:09.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:372:0) after 1m0s
[22:22:09.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:372:0)
[22:22:09.451] Timer tick, asking Buildbucket for the build status
[22:22:09.494] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:09.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:373:0) after 1m0s
[22:23:09.520] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:373:0)
[22:23:09.520] Timer tick, asking Buildbucket for the build status
[22:23:09.594] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:09.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:374:0) after 1m0s
[22:24:09.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:374:0)
[22:24:09.618] Timer tick, asking Buildbucket for the build status
[22:24:09.705] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:09.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:375:0) after 1m0s
[22:25:09.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:375:0)
[22:25:09.730] Timer tick, asking Buildbucket for the build status
[22:25:09.861] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:376:0) after 1m0s
[22:26:09.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:376:0)
[22:26:09.892] Timer tick, asking Buildbucket for the build status
[22:26:10.078] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:10.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:377:0) after 1m0s
[22:27:10.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:377:0)
[22:27:10.152] Timer tick, asking Buildbucket for the build status
[22:27:10.199] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:10.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:378:0) after 1m0s
[22:28:10.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:378:0)
[22:28:10.229] Timer tick, asking Buildbucket for the build status
[22:28:10.327] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:10.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:379:0) after 1m0s
[22:29:10.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:379:0)
[22:29:10.402] Timer tick, asking Buildbucket for the build status
[22:29:10.520] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:10.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:380:0) after 1m0s
[22:30:10.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:380:0)
[22:30:10.547] Timer tick, asking Buildbucket for the build status
[22:30:10.611] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:10.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:381:0) after 1m0s
[22:31:10.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:381:0)
[22:31:10.684] Timer tick, asking Buildbucket for the build status
[22:31:10.730] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:10.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:382:0) after 1m0s
[22:32:10.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:382:0)
[22:32:10.761] Timer tick, asking Buildbucket for the build status
[22:32:10.940] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:10.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:383:0) after 1m0s
[22:33:10.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:383:0)
[22:33:10.968] Timer tick, asking Buildbucket for the build status
[22:33:11.048] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:11.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:384:0) after 1m0s
[22:34:11.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:384:0)
[22:34:11.077] Timer tick, asking Buildbucket for the build status
[22:34:11.128] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:11.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:385:0) after 1m0s
[22:35:11.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:385:0)
[22:35:11.157] Timer tick, asking Buildbucket for the build status
[22:35:11.262] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:11.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:386:0) after 1m0s
[22:36:11.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:386:0)
[22:36:11.291] Timer tick, asking Buildbucket for the build status
[22:36:11.451] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:11.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:387:0) after 1m0s
[22:37:11.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:387:0)
[22:37:11.481] Timer tick, asking Buildbucket for the build status
[22:37:11.656] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:11.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:388:0) after 1m0s
[22:38:11.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:388:0)
[22:38:11.694] Timer tick, asking Buildbucket for the build status
[22:38:11.912] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:11.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:389:0) after 1m0s
[22:39:11.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:389:0)
[22:39:11.938] Timer tick, asking Buildbucket for the build status
[22:39:12.104] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:12.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:390:0) after 1m0s
[22:40:12.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:390:0)
[22:40:12.131] Timer tick, asking Buildbucket for the build status
[22:40:12.199] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:12.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:391:0) after 1m0s
[22:41:12.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:391:0)
[22:41:12.228] Timer tick, asking Buildbucket for the build status
[22:41:12.408] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:12.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:392:0) after 1m0s
[22:42:12.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:392:0)
[22:42:12.451] Timer tick, asking Buildbucket for the build status
[22:42:12.506] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:12.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:393:0) after 1m0s
[22:43:12.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:393:0)
[22:43:12.532] Timer tick, asking Buildbucket for the build status
[22:43:12.718] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:12.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:394:0) after 1m0s
[22:44:12.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:394:0)
[22:44:12.749] Timer tick, asking Buildbucket for the build status
[22:44:12.811] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:12.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:395:0) after 1m0s
[22:45:12.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:395:0)
[22:45:12.837] Timer tick, asking Buildbucket for the build status
[22:45:13.032] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:13.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:396:0) after 1m0s
[22:46:13.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:396:0)
[22:46:13.060] Timer tick, asking Buildbucket for the build status
[22:46:13.166] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:13.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:397:0) after 1m0s
[22:47:13.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:397:0)
[22:47:13.215] Timer tick, asking Buildbucket for the build status
[22:47:13.447] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:13.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:398:0) after 1m0s
[22:48:13.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:398:0)
[22:48:13.477] Timer tick, asking Buildbucket for the build status
[22:48:13.514] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:13.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:399:0) after 1m0s
[22:49:13.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:399:0)
[22:49:13.543] Timer tick, asking Buildbucket for the build status
[22:49:13.733] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:13.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:400:0) after 1m0s
[22:50:13.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:400:0)
[22:50:13.785] Timer tick, asking Buildbucket for the build status
[22:50:13.911] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:13.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:401:0) after 1m0s
[22:51:13.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:401:0)
[22:51:13.992] Timer tick, asking Buildbucket for the build status
[22:51:14.231] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:14.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:402:0) after 1m0s
[22:52:14.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:402:0)
[22:52:14.302] Timer tick, asking Buildbucket for the build status
[22:52:14.359] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:14.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:403:0) after 1m0s
[22:53:14.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:403:0)
[22:53:14.446] Timer tick, asking Buildbucket for the build status
[22:53:14.536] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:14.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:404:0) after 1m0s
[22:54:14.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:404:0)
[22:54:14.616] Timer tick, asking Buildbucket for the build status
[22:54:14.673] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:14.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:405:0) after 1m0s
[22:55:14.710] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:405:0)
[22:55:14.710] Timer tick, asking Buildbucket for the build status
[22:55:14.826] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:14.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:406:0) after 1m0s
[22:56:14.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:406:0)
[22:56:14.858] Timer tick, asking Buildbucket for the build status
[22:56:14.910] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:14.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:407:0) after 1m0s
[22:57:14.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:407:0)
[22:57:14.963] Timer tick, asking Buildbucket for the build status
[22:57:15.028] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:15.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:408:0) after 1m0s
[22:58:15.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:408:0)
[22:58:15.115] Timer tick, asking Buildbucket for the build status
[22:58:15.185] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:15.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:409:0) after 1m0s
[22:59:15.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:409:0)
[22:59:15.226] Timer tick, asking Buildbucket for the build status
[22:59:15.298] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:15.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:410:0) after 1m0s
[23:00:15.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:410:0)
[23:00:15.325] Timer tick, asking Buildbucket for the build status
[23:00:15.539] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:15.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:411:0) after 1m0s
--- the log has been cut here ---
[23:31:20.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:441:0)
[23:31:20.361] Timer tick, asking Buildbucket for the build status
[23:31:20.434] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:20.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:442:0) after 1m0s
[23:32:20.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:442:0)
[23:32:20.463] Timer tick, asking Buildbucket for the build status
[23:32:20.546] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:20.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:443:0) after 1m0s
[23:33:20.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:443:0)
[23:33:20.595] Timer tick, asking Buildbucket for the build status
[23:33:20.648] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:20.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:444:0) after 1m0s
--- the log has been cut here ---
[23:48:22.880] Timer tick, asking Buildbucket for the build status
[23:48:22.926] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:22.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:459:0) after 1m0s
[23:49:22.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:459:0)
[23:49:22.977] Timer tick, asking Buildbucket for the build status
[23:49:23.063] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:23.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:460:0) after 1m0s
[23:50:23.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:460:0)
[23:50:23.149] Timer tick, asking Buildbucket for the build status
[23:50:23.266] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:23.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:461:0) after 1m0s
[23:51:23.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:461:0)
[23:51:23.294] Timer tick, asking Buildbucket for the build status
[23:51:23.423] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:23.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:462:0) after 1m0s
[23:52:23.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:462:0)
[23:52:23.452] Timer tick, asking Buildbucket for the build status
[23:52:23.501] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:23.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:463:0) after 1m0s
[23:53:23.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:463:0)
[23:53:23.573] Timer tick, asking Buildbucket for the build status
[23:53:23.664] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:23.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:464:0) after 1m0s
[23:54:23.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:464:0)
[23:54:23.689] Timer tick, asking Buildbucket for the build status
[23:54:23.767] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:23.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:465:0) after 1m0s
[23:55:23.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:465:0)
[23:55:23.793] Timer tick, asking Buildbucket for the build status
[23:55:23.905] Build 8923616159529032640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:23.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9089046221961837856:466:0) after 1m0s
[23:55:46.760] Received PubSub notification, asking Buildbucket for the build status
[23:55:46.807] Build 8923616159529032640: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:55:46.807] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1548201345758790",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548173481397402",
    "failure_reason": "BUILD_FAILURE",
    "id": "8923616159529032640",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b_sz\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4016-2f4b7db\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-1cdl\"], \"pool\": [\"luci.nacl.toolchain\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 5060, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"got_revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1548173481397402, \"tags\": [\"builder:linux-pnacl-x86_64-tests-x86_64\", \"buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089046221961837856\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8923616159529032640\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"321c47ddeedcf7e87ffd2c127500e4ef9888fb69\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-1cdl\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1548193883056847",
    "status": "COMPLETED",
    "status_changed_ts": "1548201346255554",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5060",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089046221961837856",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5060",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8923616159529032640",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:buildset:commit/git/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/321c47ddeedcf7e87ffd2c127500e4ef9888fb69",
      "swarming_tag:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8923616159529032640/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089046221961837856",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:4122e711faf74a549260c6de6a369a8fe79a9d3e",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:428f261f0fefab10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548201346258951",
    "url": "https://ci.chromium.org/b/8923616159529032640",
    "utcnow_ts": "1548201346795441"
  }
}
[23:55:46.807] Invocation finished in 7h44m27.113520897s with status FAILED