Status: FAILED
Triggered by: -
Duration: 5 hours
Actions:

Definition (rev 8647dce0c5b1812bd57953fb611ed390e849ac26)

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

Incoming triggers

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[22:06:59.534] New invocation is queued and will start shortly
[22:07:01.568] Starting the invocation (attempt 1)
[22:07:01.693] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:07:01.693] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:07:01.693] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9020894926804758800",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"376086a8d121c8bd2377ee67eb119ace555ed82e\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@376086a8d121c8bd2377ee67eb119ace555ed82e\",\"title\":\"376086a8d121c8bd2377ee67eb119ace555ed82e\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/376086a8d121c8bd2377ee67eb119ace555ed82e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"376086a8d121c8bd2377ee67eb119ace555ed82e\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9020894926804758800",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/376086a8d121c8bd2377ee67eb119ace555ed82e",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/376086a8d121c8bd2377ee67eb119ace555ed82e",
    "gitiles_ref:refs/heads/master"
  ]
}
[22:07:03.416] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1613167621845895",
    "id": "8855464863714278112",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@376086a8d121c8bd2377ee67eb119ace555ed82e\", \"title\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/376086a8d121c8bd2377ee67eb119ace555ed82e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1613167623189831",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5182",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/376086a8d121c8bd2377ee67eb119ace555ed82e",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/376086a8d121c8bd2377ee67eb119ace555ed82e",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9020894926804758800",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8855464863714278112/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1613167623190039",
    "url": "https://ci.chromium.org/b/8855464863714278112",
    "utcnow_ts": "1613167623403201"
  }
}
[22:07:03.416] Task URL: https://ci.chromium.org/b/8855464863714278112
[22:07:03.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:2:0) after 1m0s
[22:08:03.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:2:0)
[22:08:03.456] Timer tick, asking Buildbucket for the build status
[22:08:03.835] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:03.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:3:0) after 1m0s
[22:09:03.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:3:0)
[22:09:03.851] Timer tick, asking Buildbucket for the build status
[22:09:04.262] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:04.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:4:0) after 1m0s
[22:10:04.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:4:0)
[22:10:04.409] Timer tick, asking Buildbucket for the build status
[22:10:04.488] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:04.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:5:0) after 1m0s
[22:11:04.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:5:0)
[22:11:04.509] Timer tick, asking Buildbucket for the build status
[22:11:04.700] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:04.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:6:0) after 1m0s
[22:12:04.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:6:0)
[22:12:04.699] Timer tick, asking Buildbucket for the build status
[22:12:04.774] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:04.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:7:0) after 1m0s
[22:13:04.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:7:0)
[22:13:04.770] Timer tick, asking Buildbucket for the build status
[22:13:04.903] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:04.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:8:0) after 1m0s
[22:14:05.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:8:0)
[22:14:05.003] Timer tick, asking Buildbucket for the build status
[22:14:05.292] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:05.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:9:0) after 1m0s
[22:15:05.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:9:0)
[22:15:05.292] Timer tick, asking Buildbucket for the build status
[22:15:05.377] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:05.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:10:0) after 1m0s
[22:16:05.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:10:0)
[22:16:05.777] Timer tick, asking Buildbucket for the build status
[22:16:05.901] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:05.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:11:0) after 1m0s
[22:17:06.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:11:0)
[22:17:06.006] Timer tick, asking Buildbucket for the build status
[22:17:06.116] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:06.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:12:0) after 1m0s
[22:18:06.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:12:0)
[22:18:06.394] Timer tick, asking Buildbucket for the build status
[22:18:06.469] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:06.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:13:0) after 1m0s
[22:19:55.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:13:0)
[22:19:55.375] Timer tick, asking Buildbucket for the build status
[22:19:55.512] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:55.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:14:0) after 1m0s
[22:20:55.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:14:0)
[22:20:55.505] Timer tick, asking Buildbucket for the build status
[22:20:55.874] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:55.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:15:0) after 1m0s
[22:21:56.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:15:0)
[22:21:56.085] Timer tick, asking Buildbucket for the build status
[22:21:56.310] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:56.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:16:0) after 1m0s
[22:22:56.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:16:0)
[22:22:56.463] Timer tick, asking Buildbucket for the build status
[22:22:56.707] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:56.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:17:0) after 1m0s
[22:23:56.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:17:0)
[22:23:56.809] Timer tick, asking Buildbucket for the build status
[22:23:56.922] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:56.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:18:0) after 1m0s
[22:24:57.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:18:0)
[22:24:57.021] Timer tick, asking Buildbucket for the build status
[22:24:57.203] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:57.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:19:0) after 1m0s
[22:25:57.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:19:0)
[22:25:57.316] Timer tick, asking Buildbucket for the build status
[22:25:57.389] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:57.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:20:0) after 1m0s
[22:26:57.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:20:0)
[22:26:57.462] Timer tick, asking Buildbucket for the build status
[22:26:57.533] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:57.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:21:0) after 1m0s
[22:27:57.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:21:0)
[22:27:57.623] Timer tick, asking Buildbucket for the build status
[22:27:57.846] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:57.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:22:0) after 1m0s
[22:28:58.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:22:0)
[22:28:58.006] Timer tick, asking Buildbucket for the build status
[22:28:58.091] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:58.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:23:0) after 1m0s
[22:29:58.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:23:0)
[22:29:58.238] Timer tick, asking Buildbucket for the build status
[22:29:58.712] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:58.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:24:0) after 1m0s
[22:30:58.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:24:0)
[22:30:58.780] Timer tick, asking Buildbucket for the build status
[22:30:59.231] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:59.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:25:0) after 1m0s
[22:31:59.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:25:0)
[22:31:59.271] Timer tick, asking Buildbucket for the build status
[22:31:59.361] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:59.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:26:0) after 1m0s
[22:32:59.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:26:0)
[22:32:59.383] Timer tick, asking Buildbucket for the build status
[22:32:59.488] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:59.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:27:0) after 1m0s
[22:33:59.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:27:0)
[22:33:59.542] Timer tick, asking Buildbucket for the build status
[22:33:59.755] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:59.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:28:0) after 1m0s
[22:34:59.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:28:0)
[22:34:59.839] Timer tick, asking Buildbucket for the build status
[22:34:59.936] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:59.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:29:0) after 1m0s
[22:35:59.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:29:0)
[22:35:59.955] Timer tick, asking Buildbucket for the build status
[22:36:00.108] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:00.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:30:0) after 1m0s
[22:37:00.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:30:0)
[22:37:00.102] Timer tick, asking Buildbucket for the build status
[22:37:00.178] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:00.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:31:0) after 1m0s
[22:38:00.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:31:0)
[22:38:00.259] Timer tick, asking Buildbucket for the build status
[22:38:00.373] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:00.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:32:0) after 1m0s
[22:39:00.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:32:0)
[22:39:00.456] Timer tick, asking Buildbucket for the build status
[22:39:00.552] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:00.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:33:0) after 1m0s
[22:40:00.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:33:0)
[22:40:00.602] Timer tick, asking Buildbucket for the build status
[22:40:00.822] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:00.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:34:0) after 1m0s
[22:41:00.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:34:0)
[22:41:00.823] Timer tick, asking Buildbucket for the build status
[22:41:00.999] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:00.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:35:0) after 1m0s
[22:42:01.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:35:0)
[22:42:01.031] Timer tick, asking Buildbucket for the build status
[22:42:01.120] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:01.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:36:0) after 1m0s
[22:43:01.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:36:0)
[22:43:01.291] Timer tick, asking Buildbucket for the build status
[22:43:01.385] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:01.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:37:0) after 1m0s
[22:44:01.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:37:0)
[22:44:01.376] Timer tick, asking Buildbucket for the build status
[22:44:01.458] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:01.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:38:0) after 1m0s
[22:45:01.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:38:0)
[22:45:01.541] Timer tick, asking Buildbucket for the build status
[22:45:01.970] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:01.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:39:0) after 1m0s
[22:46:01.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:39:0)
[22:46:01.992] Timer tick, asking Buildbucket for the build status
[22:46:02.327] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:02.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:40:0) after 1m0s
[22:47:02.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:40:0)
[22:47:02.391] Timer tick, asking Buildbucket for the build status
[22:47:02.922] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:02.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:41:0) after 1m0s
[22:48:02.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:41:0)
[22:48:02.964] Timer tick, asking Buildbucket for the build status
[22:48:03.216] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:03.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:42:0) after 1m0s
[22:49:03.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:42:0)
[22:49:03.242] Timer tick, asking Buildbucket for the build status
[22:49:03.762] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:03.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:43:0) after 1m0s
[22:50:03.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:43:0)
[22:50:03.862] Timer tick, asking Buildbucket for the build status
[22:50:04.047] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:04.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:44:0) after 1m0s
[22:51:04.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:44:0)
[22:51:04.090] Timer tick, asking Buildbucket for the build status
[22:51:04.176] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:04.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:45:0) after 1m0s
[22:52:04.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:45:0)
[22:52:04.248] Timer tick, asking Buildbucket for the build status
[22:52:04.389] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:04.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:46:0) after 1m0s
[22:53:04.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:46:0)
[22:53:04.509] Timer tick, asking Buildbucket for the build status
[22:53:05.020] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:05.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:47:0) after 1m0s
[22:54:05.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:47:0)
[22:54:05.183] Timer tick, asking Buildbucket for the build status
[22:54:05.864] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:05.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:48:0) after 1m0s
[22:55:05.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:48:0)
[22:55:05.972] Timer tick, asking Buildbucket for the build status
[22:55:06.201] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:06.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:49:0) after 1m0s
[22:56:06.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:49:0)
[22:56:06.316] Timer tick, asking Buildbucket for the build status
[22:56:06.519] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:06.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:50:0) after 1m0s
[22:57:06.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:50:0)
[22:57:06.573] Timer tick, asking Buildbucket for the build status
[22:57:06.696] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:06.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:51:0) after 1m0s
[22:58:06.724] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:51:0)
[22:58:06.724] Timer tick, asking Buildbucket for the build status
[22:58:06.887] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:06.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:52:0) after 1m0s
[22:59:06.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:52:0)
[22:59:06.964] Timer tick, asking Buildbucket for the build status
[22:59:07.142] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:07.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:53:0) after 1m0s
[23:00:07.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:53:0)
[23:00:07.172] Timer tick, asking Buildbucket for the build status
[23:00:08.970] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:08.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:54:0) after 1m0s
[23:01:09.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:54:0)
[23:01:09.083] Timer tick, asking Buildbucket for the build status
[23:01:09.197] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:09.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:55:0) after 1m0s
[23:02:09.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:55:0)
[23:02:09.276] Timer tick, asking Buildbucket for the build status
[23:02:09.548] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:09.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:56:0) after 1m0s
[23:03:09.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:56:0)
[23:03:09.656] Timer tick, asking Buildbucket for the build status
[23:03:09.735] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:09.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:57:0) after 1m0s
[23:04:09.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:57:0)
[23:04:09.829] Timer tick, asking Buildbucket for the build status
[23:04:09.913] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:09.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:58:0) after 1m0s
[23:05:09.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:58:0)
[23:05:09.992] Timer tick, asking Buildbucket for the build status
[23:05:10.194] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:10.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:59:0) after 1m0s
[23:06:10.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:59:0)
[23:06:10.194] Timer tick, asking Buildbucket for the build status
[23:06:10.301] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:10.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:60:0) after 1m0s
[23:07:10.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:60:0)
[23:07:10.362] Timer tick, asking Buildbucket for the build status
[23:07:10.711] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:10.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:61:0) after 1m0s
[23:08:10.765] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:61:0)
[23:08:10.765] Timer tick, asking Buildbucket for the build status
[23:08:10.891] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:10.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:62:0) after 1m0s
[23:09:10.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:62:0)
[23:09:10.914] Timer tick, asking Buildbucket for the build status
[23:09:11.061] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:11.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:63:0) after 1m0s
[23:10:11.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:63:0)
[23:10:11.111] Timer tick, asking Buildbucket for the build status
[23:10:11.183] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:11.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:64:0) after 1m0s
[23:11:11.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:64:0)
[23:11:11.230] Timer tick, asking Buildbucket for the build status
[23:11:11.344] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:11.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:65:0) after 1m0s
[23:12:11.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:65:0)
[23:12:11.395] Timer tick, asking Buildbucket for the build status
[23:12:11.527] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:11.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:66:0) after 1m0s
[23:13:11.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:66:0)
[23:13:11.526] Timer tick, asking Buildbucket for the build status
[23:13:11.617] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:11.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:67:0) after 1m0s
[23:14:11.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:67:0)
[23:14:11.644] Timer tick, asking Buildbucket for the build status
[23:14:11.909] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:11.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:68:0) after 1m0s
[23:15:11.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:68:0)
[23:15:11.931] Timer tick, asking Buildbucket for the build status
[23:15:12.013] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:12.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:69:0) after 1m0s
[23:16:12.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:69:0)
[23:16:12.068] Timer tick, asking Buildbucket for the build status
[23:16:12.233] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:12.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:70:0) after 1m0s
[23:17:12.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:70:0)
[23:17:12.305] Timer tick, asking Buildbucket for the build status
[23:17:12.463] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:12.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:71:0) after 1m0s
[23:18:12.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:71:0)
[23:18:12.463] Timer tick, asking Buildbucket for the build status
[23:18:12.552] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:12.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:72:0) after 1m0s
[23:19:12.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:72:0)
[23:19:12.622] Timer tick, asking Buildbucket for the build status
[23:19:12.875] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:12.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:73:0) after 1m0s
[23:20:12.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:73:0)
[23:20:12.987] Timer tick, asking Buildbucket for the build status
[23:20:13.061] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:13.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:74:0) after 1m0s
[23:21:13.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:74:0)
[23:21:13.133] Timer tick, asking Buildbucket for the build status
[23:21:13.308] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:13.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:75:0) after 1m0s
[23:22:13.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:75:0)
[23:22:13.375] Timer tick, asking Buildbucket for the build status
[23:22:13.466] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:13.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:76:0) after 1m0s
[23:23:13.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:76:0)
[23:23:13.458] Timer tick, asking Buildbucket for the build status
[23:23:13.631] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:13.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:77:0) after 1m0s
[23:24:13.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:77:0)
[23:24:13.673] Timer tick, asking Buildbucket for the build status
[23:24:13.773] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:13.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:78:0) after 1m0s
[23:25:13.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:78:0)
[23:25:13.776] Timer tick, asking Buildbucket for the build status
[23:25:14.040] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:14.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:79:0) after 1m0s
[23:26:14.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:79:0)
[23:26:14.173] Timer tick, asking Buildbucket for the build status
[23:26:14.274] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:14.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:80:0) after 1m0s
[23:27:14.422] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:80:0)
[23:27:14.422] Timer tick, asking Buildbucket for the build status
[23:27:14.609] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:14.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:81:0) after 1m0s
[23:28:14.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:81:0)
[23:28:14.697] Timer tick, asking Buildbucket for the build status
[23:28:15.737] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:15.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:82:0) after 1m0s
[23:29:15.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:82:0)
[23:29:15.804] Timer tick, asking Buildbucket for the build status
[23:29:15.885] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:15.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:83:0) after 1m0s
[23:30:16.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:83:0)
[23:30:16.114] Timer tick, asking Buildbucket for the build status
[23:30:16.207] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:16.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:84:0) after 1m0s
[23:31:16.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:84:0)
[23:31:16.258] Timer tick, asking Buildbucket for the build status
[23:31:16.369] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:16.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:85:0) after 1m0s
[23:32:16.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:85:0)
[23:32:16.414] Timer tick, asking Buildbucket for the build status
[23:32:16.524] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:16.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:86:0) after 1m0s
[23:33:16.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:86:0)
[23:33:16.636] Timer tick, asking Buildbucket for the build status
[23:33:16.744] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:16.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:87:0) after 1m0s
[23:34:16.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:87:0)
[23:34:16.832] Timer tick, asking Buildbucket for the build status
[23:34:16.939] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:16.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:88:0) after 1m0s
[23:35:17.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:88:0)
[23:35:17.096] Timer tick, asking Buildbucket for the build status
[23:35:17.357] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:17.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:89:0) after 1m0s
[23:36:17.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:89:0)
[23:36:17.474] Timer tick, asking Buildbucket for the build status
[23:36:17.550] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:17.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:90:0) after 1m0s
[23:37:17.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:90:0)
[23:37:17.600] Timer tick, asking Buildbucket for the build status
[23:37:17.724] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:17.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:91:0) after 1m0s
[23:38:17.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:91:0)
[23:38:17.774] Timer tick, asking Buildbucket for the build status
[23:38:17.850] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:17.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:92:0) after 1m0s
[23:39:17.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:92:0)
[23:39:17.859] Timer tick, asking Buildbucket for the build status
[23:39:18.016] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:18.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:93:0) after 1m0s
[23:40:18.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:93:0)
[23:40:18.012] Timer tick, asking Buildbucket for the build status
[23:40:18.149] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:18.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:94:0) after 1m0s
[23:41:18.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:94:0)
[23:41:18.196] Timer tick, asking Buildbucket for the build status
[23:41:18.275] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:18.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:95:0) after 1m0s
[23:42:18.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:95:0)
[23:42:18.270] Timer tick, asking Buildbucket for the build status
[23:42:18.391] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:18.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:96:0) after 1m0s
[23:43:18.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:96:0)
[23:43:18.601] Timer tick, asking Buildbucket for the build status
[23:43:18.685] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:18.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:97:0) after 1m0s
[23:44:18.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:97:0)
[23:44:18.748] Timer tick, asking Buildbucket for the build status
[23:44:18.844] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:18.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:98:0) after 1m0s
[23:45:19.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:98:0)
[23:45:19.075] Timer tick, asking Buildbucket for the build status
[23:45:19.208] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:19.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:99:0) after 1m0s
[23:46:19.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:99:0)
[23:46:19.230] Timer tick, asking Buildbucket for the build status
[23:46:19.326] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:19.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:100:0) after 1m0s
[23:47:19.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:100:0)
[23:47:19.381] Timer tick, asking Buildbucket for the build status
[23:47:19.507] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:19.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:101:0) after 1m0s
[23:48:19.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:101:0)
[23:48:19.614] Timer tick, asking Buildbucket for the build status
[23:48:19.697] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:19.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:102:0) after 1m0s
[23:49:19.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:102:0)
[23:49:19.729] Timer tick, asking Buildbucket for the build status
[23:49:19.814] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:19.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:103:0) after 1m0s
[23:50:19.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:103:0)
[23:50:19.838] Timer tick, asking Buildbucket for the build status
[23:50:19.969] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:19.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:104:0) after 1m0s
[23:51:20.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:104:0)
[23:51:20.024] Timer tick, asking Buildbucket for the build status
[23:51:20.113] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:20.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:105:0) after 1m0s
[23:52:20.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:105:0)
[23:52:20.158] Timer tick, asking Buildbucket for the build status
[23:52:20.439] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:20.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:106:0) after 1m0s
[23:53:20.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:106:0)
[23:53:20.503] Timer tick, asking Buildbucket for the build status
[23:53:20.594] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:20.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:107:0) after 1m0s
[23:54:20.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:107:0)
[23:54:20.789] Timer tick, asking Buildbucket for the build status
[23:54:20.889] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:20.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:108:0) after 1m0s
[23:55:21.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:108:0)
[23:55:21.063] Timer tick, asking Buildbucket for the build status
[23:55:21.151] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:21.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:109:0) after 1m0s
[23:56:21.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:109:0)
[23:56:21.133] Timer tick, asking Buildbucket for the build status
[23:56:21.382] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:21.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:110:0) after 1m0s
[23:57:21.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:110:0)
[23:57:21.415] Timer tick, asking Buildbucket for the build status
[23:57:21.567] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:21.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:111:0) after 1m0s
[23:58:21.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:111:0)
[23:58:21.637] Timer tick, asking Buildbucket for the build status
[23:58:21.715] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:21.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:112:0) after 1m0s
[23:59:21.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:112:0)
[23:59:21.908] Timer tick, asking Buildbucket for the build status
[23:59:22.030] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:22.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:113:0) after 1m0s
[00:00:22.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:113:0)
[00:00:22.240] Timer tick, asking Buildbucket for the build status
[00:00:22.368] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:22.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:114:0) after 1m0s
[00:01:22.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:114:0)
[00:01:22.591] Timer tick, asking Buildbucket for the build status
[00:01:22.697] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:22.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:115:0) after 1m0s
[00:02:22.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:115:0)
[00:02:22.776] Timer tick, asking Buildbucket for the build status
[00:02:22.878] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:22.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:116:0) after 1m0s
[00:03:22.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:116:0)
[00:03:22.906] Timer tick, asking Buildbucket for the build status
[00:03:22.999] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:22.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:117:0) after 1m0s
[00:04:23.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:117:0)
[00:04:23.094] Timer tick, asking Buildbucket for the build status
[00:04:23.287] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:23.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:118:0) after 1m0s
[00:05:23.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:118:0)
[00:05:23.320] Timer tick, asking Buildbucket for the build status
[00:05:23.463] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:23.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:119:0) after 1m0s
[00:06:23.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:119:0)
[00:06:23.486] Timer tick, asking Buildbucket for the build status
[00:06:23.769] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:23.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:120:0) after 1m0s
[00:07:23.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:120:0)
[00:07:23.775] Timer tick, asking Buildbucket for the build status
[00:07:23.892] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:23.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:121:0) after 1m0s
[00:08:23.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:121:0)
[00:08:23.907] Timer tick, asking Buildbucket for the build status
[00:08:24.050] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:24.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:122:0) after 1m0s
[00:09:24.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:122:0)
[00:09:24.081] Timer tick, asking Buildbucket for the build status
[00:09:24.161] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:24.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:123:0) after 1m0s
[00:10:24.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:123:0)
[00:10:24.171] Timer tick, asking Buildbucket for the build status
[00:10:24.338] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:24.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:124:0) after 1m0s
[00:11:24.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:124:0)
[00:11:24.392] Timer tick, asking Buildbucket for the build status
[00:11:24.473] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:24.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:125:0) after 1m0s
[00:12:24.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:125:0)
[00:12:24.550] Timer tick, asking Buildbucket for the build status
[00:12:24.969] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:24.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:126:0) after 1m0s
[00:13:25.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:126:0)
[00:13:25.008] Timer tick, asking Buildbucket for the build status
[00:13:25.101] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:25.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:127:0) after 1m0s
[00:14:25.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:127:0)
[00:14:25.154] Timer tick, asking Buildbucket for the build status
[00:14:25.250] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:25.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:128:0) after 1m0s
[00:15:25.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:128:0)
[00:15:25.297] Timer tick, asking Buildbucket for the build status
[00:15:25.375] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:25.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:129:0) after 1m0s
[00:16:25.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:129:0)
[00:16:25.416] Timer tick, asking Buildbucket for the build status
[00:16:25.708] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:25.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:130:0) after 1m0s
[00:17:25.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:130:0)
[00:17:25.813] Timer tick, asking Buildbucket for the build status
[00:17:26.226] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:26.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:131:0) after 1m0s
[00:18:26.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:131:0)
[00:18:26.235] Timer tick, asking Buildbucket for the build status
[00:18:26.317] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:26.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:132:0) after 1m0s
[00:19:26.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:132:0)
[00:19:26.384] Timer tick, asking Buildbucket for the build status
[00:19:26.636] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:26.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:133:0) after 1m0s
[00:20:26.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:133:0)
[00:20:26.728] Timer tick, asking Buildbucket for the build status
[00:20:26.804] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:26.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:134:0) after 1m0s
[00:21:26.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:134:0)
[00:21:26.835] Timer tick, asking Buildbucket for the build status
[00:21:27.176] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:27.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:135:0) after 1m0s
[00:22:27.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:135:0)
[00:22:27.367] Timer tick, asking Buildbucket for the build status
[00:22:27.498] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:27.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:136:0) after 1m0s
[00:23:27.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:136:0)
[00:23:27.706] Timer tick, asking Buildbucket for the build status
[00:23:27.782] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:27.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:137:0) after 1m0s
[00:24:27.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:137:0)
[00:24:27.939] Timer tick, asking Buildbucket for the build status
[00:24:28.068] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:28.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:138:0) after 1m0s
[00:25:28.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:138:0)
[00:25:28.215] Timer tick, asking Buildbucket for the build status
[00:25:28.729] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:28.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:139:0) after 1m0s
[00:26:28.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:139:0)
[00:26:28.772] Timer tick, asking Buildbucket for the build status
[00:26:29.076] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:29.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:140:0) after 1m0s
[00:27:29.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:140:0)
[00:27:29.120] Timer tick, asking Buildbucket for the build status
[00:27:29.224] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:29.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:141:0) after 1m0s
[00:28:29.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:141:0)
[00:28:29.291] Timer tick, asking Buildbucket for the build status
[00:28:29.356] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:29.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:142:0) after 1m0s
[00:29:29.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:142:0)
[00:29:29.419] Timer tick, asking Buildbucket for the build status
[00:29:29.497] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:29.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:143:0) after 1m0s
[00:30:29.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:143:0)
[00:30:29.616] Timer tick, asking Buildbucket for the build status
[00:30:29.691] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:29.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:144:0) after 1m0s
[00:31:29.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:144:0)
[00:31:29.758] Timer tick, asking Buildbucket for the build status
[00:31:29.898] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:29.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:145:0) after 1m0s
[00:32:29.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:145:0)
[00:32:29.965] Timer tick, asking Buildbucket for the build status
[00:32:30.081] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:30.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:146:0) after 1m0s
[00:33:30.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:146:0)
[00:33:30.108] Timer tick, asking Buildbucket for the build status
[00:33:30.200] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:30.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:147:0) after 1m0s
[00:34:30.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:147:0)
[00:34:30.262] Timer tick, asking Buildbucket for the build status
[00:34:30.366] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:30.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:148:0) after 1m0s
[00:35:30.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:148:0)
[00:35:30.341] Timer tick, asking Buildbucket for the build status
[00:35:30.535] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:30.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:149:0) after 1m0s
[00:36:30.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:149:0)
[00:36:30.622] Timer tick, asking Buildbucket for the build status
[00:36:30.755] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:30.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:150:0) after 1m0s
[00:37:30.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:150:0)
[00:37:30.827] Timer tick, asking Buildbucket for the build status
[00:37:31.095] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:31.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:151:0) after 1m0s
[00:38:31.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:151:0)
[00:38:31.152] Timer tick, asking Buildbucket for the build status
[00:38:31.260] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:31.260] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:152:0) after 1m0s
[00:39:31.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:152:0)
[00:39:31.367] Timer tick, asking Buildbucket for the build status
[00:39:31.629] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:31.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:153:0) after 1m0s
[00:40:31.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:153:0)
[00:40:31.676] Timer tick, asking Buildbucket for the build status
[00:40:32.090] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:32.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:154:0) after 1m0s
[00:41:32.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:154:0)
[00:41:32.159] Timer tick, asking Buildbucket for the build status
[00:41:32.300] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:32.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:155:0) after 1m0s
[00:42:32.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:155:0)
[00:42:32.389] Timer tick, asking Buildbucket for the build status
[00:42:32.511] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:32.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:156:0) after 1m0s
[00:43:32.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:156:0)
[00:43:32.555] Timer tick, asking Buildbucket for the build status
[00:43:32.634] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:32.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:157:0) after 1m0s
[00:44:32.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:157:0)
[00:44:32.792] Timer tick, asking Buildbucket for the build status
[00:44:32.883] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:32.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:158:0) after 1m0s
[00:45:32.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:158:0)
[00:45:32.908] Timer tick, asking Buildbucket for the build status
[00:45:33.226] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:33.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:159:0) after 1m0s
[00:46:33.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:159:0)
[00:46:33.235] Timer tick, asking Buildbucket for the build status
[00:46:33.315] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:33.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:160:0) after 1m0s
[00:47:33.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:160:0)
[00:47:33.361] Timer tick, asking Buildbucket for the build status
[00:47:33.717] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:33.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:161:0) after 1m0s
[00:48:33.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:161:0)
[00:48:33.763] Timer tick, asking Buildbucket for the build status
[00:48:34.025] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:34.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:162:0) after 1m0s
[00:49:34.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:162:0)
[00:49:34.195] Timer tick, asking Buildbucket for the build status
[00:49:34.281] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:34.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:163:0) after 1m0s
[00:50:34.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:163:0)
[00:50:34.352] Timer tick, asking Buildbucket for the build status
[00:50:34.438] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:34.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:164:0) after 1m0s
[00:51:34.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:164:0)
[00:51:34.492] Timer tick, asking Buildbucket for the build status
[00:51:34.576] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:34.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:165:0) after 1m0s
[00:52:34.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:165:0)
[00:52:34.697] Timer tick, asking Buildbucket for the build status
[00:52:34.928] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:34.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:166:0) after 1m0s
[00:53:34.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:166:0)
[00:53:34.911] Timer tick, asking Buildbucket for the build status
[00:53:34.997] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:34.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:167:0) after 1m0s
[00:54:35.070] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:167:0)
[00:54:35.070] Timer tick, asking Buildbucket for the build status
[00:54:35.188] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:35.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:168:0) after 1m0s
[00:55:35.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:168:0)
[00:55:35.188] Timer tick, asking Buildbucket for the build status
[00:55:35.351] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:35.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:169:0) after 1m0s
[00:56:35.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:169:0)
[00:56:35.375] Timer tick, asking Buildbucket for the build status
[00:56:35.560] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:35.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:170:0) after 1m0s
[00:57:35.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:170:0)
[00:57:35.577] Timer tick, asking Buildbucket for the build status
[00:57:35.699] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:35.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:171:0) after 1m0s
[00:58:35.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:171:0)
[00:58:35.674] Timer tick, asking Buildbucket for the build status
[00:58:35.778] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:35.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:172:0) after 1m0s
[00:59:35.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:172:0)
[00:59:35.803] Timer tick, asking Buildbucket for the build status
[00:59:36.012] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:36.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:173:0) after 1m0s
[01:00:36.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:173:0)
[01:00:36.074] Timer tick, asking Buildbucket for the build status
[01:00:36.464] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:36.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:174:0) after 1m0s
[01:01:36.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:174:0)
[01:01:36.542] Timer tick, asking Buildbucket for the build status
[01:01:36.636] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:36.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:175:0) after 1m0s
[01:02:36.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:175:0)
[01:02:36.735] Timer tick, asking Buildbucket for the build status
[01:02:36.816] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:36.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:176:0) after 1m0s
[01:03:36.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:176:0)
[01:03:36.838] Timer tick, asking Buildbucket for the build status
[01:03:36.942] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:36.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:177:0) after 1m0s
[01:04:36.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:177:0)
[01:04:36.954] Timer tick, asking Buildbucket for the build status
[01:04:37.050] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:37.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:178:0) after 1m0s
[01:05:37.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:178:0)
[01:05:37.149] Timer tick, asking Buildbucket for the build status
[01:05:37.944] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:37.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:179:0) after 1m0s
[01:06:38.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:179:0)
[01:06:38.015] Timer tick, asking Buildbucket for the build status
[01:06:38.172] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:38.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:180:0) after 1m0s
[01:07:38.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:180:0)
[01:07:38.200] Timer tick, asking Buildbucket for the build status
[01:07:38.305] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:38.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:181:0) after 1m0s
[01:08:38.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:181:0)
[01:08:38.328] Timer tick, asking Buildbucket for the build status
[01:08:38.424] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:38.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:182:0) after 1m0s
[01:09:38.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:182:0)
[01:09:38.445] Timer tick, asking Buildbucket for the build status
[01:09:38.714] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:38.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:183:0) after 1m0s
[01:10:38.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:183:0)
[01:10:38.737] Timer tick, asking Buildbucket for the build status
[01:10:38.814] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:38.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:184:0) after 1m0s
[01:11:38.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:184:0)
[01:11:38.910] Timer tick, asking Buildbucket for the build status
[01:11:39.267] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:39.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:185:0) after 1m0s
[01:12:39.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:185:0)
[01:12:39.259] Timer tick, asking Buildbucket for the build status
[01:12:39.599] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:39.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:186:0) after 1m0s
[01:13:39.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:186:0)
[01:13:39.672] Timer tick, asking Buildbucket for the build status
[01:13:39.828] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:39.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:187:0) after 1m0s
[01:14:39.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:187:0)
[01:14:39.877] Timer tick, asking Buildbucket for the build status
[01:14:39.952] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:39.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:188:0) after 1m0s
[01:15:40.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:188:0)
[01:15:40.004] Timer tick, asking Buildbucket for the build status
[01:15:40.118] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:40.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:189:0) after 1m0s
[01:16:40.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:189:0)
[01:16:40.145] Timer tick, asking Buildbucket for the build status
[01:16:40.226] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:40.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:190:0) after 1m0s
[01:17:40.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:190:0)
[01:17:40.291] Timer tick, asking Buildbucket for the build status
[01:17:40.386] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:40.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:191:0) after 1m0s
[01:18:40.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:191:0)
[01:18:40.524] Timer tick, asking Buildbucket for the build status
[01:18:40.617] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:40.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:192:0) after 1m0s
[01:19:40.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:192:0)
[01:19:40.787] Timer tick, asking Buildbucket for the build status
[01:19:40.884] Build 8855464863714278112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:40.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:193:0) after 1m0s
[01:20:10.734] Received PubSub notification, asking Buildbucket for the build status
[01:20:10.908] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:193:0)
[01:20:40.955] Timer tick, asking Buildbucket for the build status
[01:20:41.214] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:41.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:195:0) after 1m0s
[01:21:41.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:195:0)
[01:21:41.352] Timer tick, asking Buildbucket for the build status
[01:21:41.669] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:41.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:196:0) after 1m0s
[01:22:41.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:196:0)
[01:22:41.672] Timer tick, asking Buildbucket for the build status
[01:22:41.836] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:41.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:197:0) after 1m0s
[01:23:41.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:197:0)
[01:23:41.950] Timer tick, asking Buildbucket for the build status
[01:23:42.033] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:42.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:198:0) after 1m0s
[01:24:42.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:198:0)
[01:24:42.054] Timer tick, asking Buildbucket for the build status
[01:24:42.165] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:42.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:199:0) after 1m0s
[01:25:42.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:199:0)
[01:25:42.292] Timer tick, asking Buildbucket for the build status
[01:25:42.397] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:42.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:200:0) after 1m0s
[01:26:42.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:200:0)
[01:26:42.418] Timer tick, asking Buildbucket for the build status
[01:26:42.755] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:42.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:201:0) after 1m0s
[01:27:42.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:201:0)
[01:27:42.839] Timer tick, asking Buildbucket for the build status
[01:27:42.944] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:42.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:202:0) after 1m0s
[01:28:43.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:202:0)
[01:28:43.001] Timer tick, asking Buildbucket for the build status
[01:28:43.089] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:43.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:203:0) after 1m0s
[01:29:43.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:203:0)
[01:29:43.141] Timer tick, asking Buildbucket for the build status
[01:29:43.299] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:43.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:204:0) after 1m0s
[01:30:43.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:204:0)
[01:30:43.321] Timer tick, asking Buildbucket for the build status
[01:30:43.405] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:43.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:205:0) after 1m0s
[01:31:43.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:205:0)
[01:31:43.599] Timer tick, asking Buildbucket for the build status
[01:31:43.682] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:43.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:206:0) after 1m0s
[01:32:43.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:206:0)
[01:32:43.787] Timer tick, asking Buildbucket for the build status
[01:32:43.912] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:43.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:207:0) after 1m0s
[01:33:43.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:207:0)
[01:33:43.929] Timer tick, asking Buildbucket for the build status
[01:33:44.018] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:44.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:208:0) after 1m0s
[01:34:44.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:208:0)
[01:34:44.090] Timer tick, asking Buildbucket for the build status
[01:34:44.243] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:44.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:209:0) after 1m0s
[01:35:44.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:209:0)
[01:35:44.336] Timer tick, asking Buildbucket for the build status
[01:35:44.415] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:44.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:210:0) after 1m0s
[01:36:44.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:210:0)
[01:36:44.495] Timer tick, asking Buildbucket for the build status
[01:36:44.663] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:44.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:211:0) after 1m0s
[01:37:44.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:211:0)
[01:37:44.737] Timer tick, asking Buildbucket for the build status
[01:37:45.090] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:45.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:212:0) after 1m0s
[01:38:45.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:212:0)
[01:38:45.226] Timer tick, asking Buildbucket for the build status
[01:38:45.344] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:45.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:213:0) after 1m0s
[01:39:45.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:213:0)
[01:39:45.401] Timer tick, asking Buildbucket for the build status
[01:39:45.512] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:45.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:214:0) after 1m0s
[01:40:45.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:214:0)
[01:40:45.615] Timer tick, asking Buildbucket for the build status
[01:40:45.703] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:45.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:215:0) after 1m0s
[01:41:45.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:215:0)
[01:41:45.793] Timer tick, asking Buildbucket for the build status
[01:41:45.905] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:45.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:216:0) after 1m0s
[01:42:45.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:216:0)
[01:42:45.991] Timer tick, asking Buildbucket for the build status
[01:42:46.315] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:46.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:217:0) after 1m0s
[01:43:46.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:217:0)
[01:43:46.378] Timer tick, asking Buildbucket for the build status
[01:43:46.472] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:46.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:218:0) after 1m0s
[01:44:46.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:218:0)
[01:44:46.695] Timer tick, asking Buildbucket for the build status
[01:44:46.777] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:46.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:219:0) after 1m0s
[01:45:46.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:219:0)
[01:45:46.826] Timer tick, asking Buildbucket for the build status
[01:45:46.896] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:46.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:220:0) after 1m0s
[01:46:47.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:220:0)
[01:46:47.007] Timer tick, asking Buildbucket for the build status
[01:46:47.085] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:47.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:221:0) after 1m0s
[01:47:47.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:221:0)
[01:47:47.217] Timer tick, asking Buildbucket for the build status
[01:47:47.412] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:47.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:222:0) after 1m0s
[01:48:47.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:222:0)
[01:48:47.516] Timer tick, asking Buildbucket for the build status
[01:48:47.602] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:47.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:223:0) after 1m0s
[01:49:47.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:223:0)
[01:49:47.732] Timer tick, asking Buildbucket for the build status
[01:49:47.815] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:47.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:224:0) after 1m0s
[01:50:47.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:224:0)
[01:50:47.925] Timer tick, asking Buildbucket for the build status
[01:50:48.003] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:48.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:225:0) after 1m0s
[01:51:48.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:225:0)
[01:51:48.047] Timer tick, asking Buildbucket for the build status
[01:51:48.130] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:48.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:226:0) after 1m0s
[01:52:48.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:226:0)
[01:52:48.192] Timer tick, asking Buildbucket for the build status
[01:52:48.284] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:48.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:227:0) after 1m0s
[01:53:48.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:227:0)
[01:53:48.337] Timer tick, asking Buildbucket for the build status
[01:53:48.439] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:48.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:228:0) after 1m0s
[01:54:48.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:228:0)
[01:54:48.516] Timer tick, asking Buildbucket for the build status
[01:54:48.616] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:48.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:229:0) after 1m0s
[01:55:48.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:229:0)
[01:55:48.649] Timer tick, asking Buildbucket for the build status
[01:55:48.721] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:48.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:230:0) after 1m0s
[01:56:48.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:230:0)
[01:56:48.820] Timer tick, asking Buildbucket for the build status
[01:56:49.230] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:49.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:231:0) after 1m0s
[01:57:49.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:231:0)
[01:57:49.330] Timer tick, asking Buildbucket for the build status
[01:57:49.519] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:49.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:232:0) after 1m0s
[01:58:49.630] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:232:0)
[01:58:49.630] Timer tick, asking Buildbucket for the build status
[01:58:49.770] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:49.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:233:0) after 1m0s
[01:59:49.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:233:0)
[01:59:49.798] Timer tick, asking Buildbucket for the build status
[01:59:49.870] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:49.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:234:0) after 1m0s
[02:00:49.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:234:0)
[02:00:49.895] Timer tick, asking Buildbucket for the build status
[02:00:49.992] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:49.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:235:0) after 1m0s
[02:01:50.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:235:0)
[02:01:50.001] Timer tick, asking Buildbucket for the build status
[02:01:50.088] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:50.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:236:0) after 1m0s
[02:02:50.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:236:0)
[02:02:50.192] Timer tick, asking Buildbucket for the build status
[02:02:50.409] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:50.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:237:0) after 1m0s
[02:03:50.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:237:0)
[02:03:50.457] Timer tick, asking Buildbucket for the build status
[02:03:50.581] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:50.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:238:0) after 1m0s
[02:04:50.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:238:0)
[02:04:50.707] Timer tick, asking Buildbucket for the build status
[02:04:50.808] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:50.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:239:0) after 1m0s
[02:05:50.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:239:0)
[02:05:50.903] Timer tick, asking Buildbucket for the build status
[02:05:51.003] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:51.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:240:0) after 1m0s
[02:06:51.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:240:0)
[02:06:51.089] Timer tick, asking Buildbucket for the build status
[02:06:51.181] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:51.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:241:0) after 1m0s
[02:07:51.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:241:0)
[02:07:51.263] Timer tick, asking Buildbucket for the build status
[02:07:51.365] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:51.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:242:0) after 1m0s
[02:08:51.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:242:0)
[02:08:51.371] Timer tick, asking Buildbucket for the build status
[02:08:51.544] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:51.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:243:0) after 1m0s
[02:09:51.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:243:0)
[02:09:51.643] Timer tick, asking Buildbucket for the build status
[02:09:51.764] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:51.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:244:0) after 1m0s
[02:10:51.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:244:0)
[02:10:51.875] Timer tick, asking Buildbucket for the build status
[02:10:51.990] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:51.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:245:0) after 1m0s
[02:11:52.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:245:0)
[02:11:52.068] Timer tick, asking Buildbucket for the build status
[02:11:52.174] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:52.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:246:0) after 1m0s
[02:12:52.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:246:0)
[02:12:52.196] Timer tick, asking Buildbucket for the build status
[02:12:52.305] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:52.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:247:0) after 1m0s
[02:13:52.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:247:0)
[02:13:52.427] Timer tick, asking Buildbucket for the build status
[02:13:52.894] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:52.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:248:0) after 1m0s
[02:14:52.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:248:0)
[02:14:52.921] Timer tick, asking Buildbucket for the build status
[02:14:53.107] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:53.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:249:0) after 1m0s
[02:15:53.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:249:0)
[02:15:53.231] Timer tick, asking Buildbucket for the build status
[02:15:53.330] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:53.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:250:0) after 1m0s
[02:16:53.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:250:0)
[02:16:53.447] Timer tick, asking Buildbucket for the build status
[02:16:53.602] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:53.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:251:0) after 1m0s
[02:17:53.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:251:0)
[02:17:53.706] Timer tick, asking Buildbucket for the build status
[02:17:53.845] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:53.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:252:0) after 1m0s
[02:18:53.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:252:0)
[02:18:53.949] Timer tick, asking Buildbucket for the build status
[02:18:54.086] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:54.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:253:0) after 1m0s
[02:19:54.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:253:0)
[02:19:54.157] Timer tick, asking Buildbucket for the build status
[02:19:54.264] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:54.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:254:0) after 1m0s
[02:20:54.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:254:0)
[02:20:54.356] Timer tick, asking Buildbucket for the build status
[02:20:54.439] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:54.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:255:0) after 1m0s
[02:21:54.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:255:0)
[02:21:54.507] Timer tick, asking Buildbucket for the build status
[02:21:54.901] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:54.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:256:0) after 1m0s
[02:22:54.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:256:0)
[02:22:54.954] Timer tick, asking Buildbucket for the build status
[02:22:55.065] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:55.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:257:0) after 1m0s
[02:23:55.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:257:0)
[02:23:55.109] Timer tick, asking Buildbucket for the build status
[02:23:55.207] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:55.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:258:0) after 1m0s
[02:24:55.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:258:0)
[02:24:55.342] Timer tick, asking Buildbucket for the build status
[02:24:55.693] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:55.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:259:0) after 1m0s
[02:25:55.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:259:0)
[02:25:55.791] Timer tick, asking Buildbucket for the build status
[02:25:55.890] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:55.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:260:0) after 1m0s
[02:26:55.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:260:0)
[02:26:55.967] Timer tick, asking Buildbucket for the build status
[02:26:56.042] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:56.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:261:0) after 1m0s
[02:27:56.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:261:0)
[02:27:56.067] Timer tick, asking Buildbucket for the build status
[02:27:56.418] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:262:0) after 1m0s
[02:28:56.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:262:0)
[02:28:56.539] Timer tick, asking Buildbucket for the build status
[02:28:56.625] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:56.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:263:0) after 1m0s
[02:29:56.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:263:0)
[02:29:56.682] Timer tick, asking Buildbucket for the build status
[02:29:57.034] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:57.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:264:0) after 1m0s
[02:30:57.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:264:0)
[02:30:57.153] Timer tick, asking Buildbucket for the build status
[02:30:57.490] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:57.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:265:0) after 1m0s
[02:31:57.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:265:0)
[02:31:57.616] Timer tick, asking Buildbucket for the build status
[02:31:57.722] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:57.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:266:0) after 1m0s
[02:32:57.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:266:0)
[02:32:57.821] Timer tick, asking Buildbucket for the build status
[02:32:57.954] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:57.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:267:0) after 1m0s
[02:33:58.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:267:0)
[02:33:58.016] Timer tick, asking Buildbucket for the build status
[02:33:58.213] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:58.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:268:0) after 1m0s
[02:34:58.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:268:0)
[02:34:58.248] Timer tick, asking Buildbucket for the build status
[02:34:58.422] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.422] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:269:0) after 1m0s
[02:35:58.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:269:0)
[02:35:58.474] Timer tick, asking Buildbucket for the build status
[02:35:58.696] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:58.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:270:0) after 1m0s
[02:36:58.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:270:0)
[02:36:58.728] Timer tick, asking Buildbucket for the build status
[02:36:58.809] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:58.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:271:0) after 1m0s
[02:37:58.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:271:0)
[02:37:58.862] Timer tick, asking Buildbucket for the build status
[02:37:59.048] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:59.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:272:0) after 1m0s
[02:38:59.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:272:0)
[02:38:59.129] Timer tick, asking Buildbucket for the build status
[02:38:59.219] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:59.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:273:0) after 1m0s
[02:39:59.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:273:0)
[02:39:59.325] Timer tick, asking Buildbucket for the build status
[02:39:59.534] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:59.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:274:0) after 1m0s
[02:40:59.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:274:0)
[02:40:59.647] Timer tick, asking Buildbucket for the build status
[02:40:59.734] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:59.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:275:0) after 1m0s
[02:41:59.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:275:0)
[02:41:59.826] Timer tick, asking Buildbucket for the build status
[02:41:59.912] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:59.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:276:0) after 1m0s
[02:43:00.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:276:0)
[02:43:00.017] Timer tick, asking Buildbucket for the build status
[02:43:00.385] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:00.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:277:0) after 1m0s
[02:44:00.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:277:0)
[02:44:00.569] Timer tick, asking Buildbucket for the build status
[02:44:00.653] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:00.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:278:0) after 1m0s
[02:45:00.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:278:0)
[02:45:00.673] Timer tick, asking Buildbucket for the build status
[02:45:00.784] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:00.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:279:0) after 1m0s
[02:46:01.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:279:0)
[02:46:01.443] Timer tick, asking Buildbucket for the build status
[02:46:01.554] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:01.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:280:0) after 1m0s
[02:47:01.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:280:0)
[02:47:01.917] Timer tick, asking Buildbucket for the build status
[02:47:02.142] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:02.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:281:0) after 1m0s
[02:48:02.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:281:0)
[02:48:02.785] Timer tick, asking Buildbucket for the build status
[02:48:02.973] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:02.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:282:0) after 1m0s
[02:49:03.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:282:0)
[02:49:03.273] Timer tick, asking Buildbucket for the build status
[02:49:03.432] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:03.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:283:0) after 1m0s
[02:50:04.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:283:0)
[02:50:04.226] Timer tick, asking Buildbucket for the build status
[02:50:04.486] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:04.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:284:0) after 1m0s
[02:51:04.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:284:0)
[02:51:04.856] Timer tick, asking Buildbucket for the build status
[02:51:04.986] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:04.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:285:0) after 1m0s
[02:52:05.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:285:0)
[02:52:05.252] Timer tick, asking Buildbucket for the build status
[02:52:05.331] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:05.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:286:0) after 1m0s
[02:53:05.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:286:0)
[02:53:05.402] Timer tick, asking Buildbucket for the build status
[02:53:05.533] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:05.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:287:0) after 1m0s
[02:54:06.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:287:0)
[02:54:06.073] Timer tick, asking Buildbucket for the build status
[02:54:06.176] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:06.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:288:0) after 1m0s
[02:55:06.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:288:0)
[02:55:06.316] Timer tick, asking Buildbucket for the build status
[02:55:06.429] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:06.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:289:0) after 1m0s
[02:56:06.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:289:0)
[02:56:06.432] Timer tick, asking Buildbucket for the build status
[02:56:06.523] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:06.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:290:0) after 1m0s
[02:57:06.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:290:0)
[02:57:06.637] Timer tick, asking Buildbucket for the build status
[02:57:06.861] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:06.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:291:0) after 1m0s
[02:58:06.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:291:0)
[02:58:06.938] Timer tick, asking Buildbucket for the build status
[02:58:07.070] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:07.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:292:0) after 1m0s
[02:59:07.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:292:0)
[02:59:07.098] Timer tick, asking Buildbucket for the build status
[02:59:07.167] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:07.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:293:0) after 1m0s
[03:00:07.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:293:0)
[03:00:07.245] Timer tick, asking Buildbucket for the build status
[03:00:07.418] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:07.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:294:0) after 1m0s
[03:01:07.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:294:0)
[03:01:07.992] Timer tick, asking Buildbucket for the build status
[03:01:08.203] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:08.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:295:0) after 1m0s
[03:02:08.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:295:0)
[03:02:08.294] Timer tick, asking Buildbucket for the build status
[03:02:08.394] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:08.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:296:0) after 1m0s
[03:03:08.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:296:0)
[03:03:08.507] Timer tick, asking Buildbucket for the build status
[03:03:08.725] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:08.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:297:0) after 1m0s
[03:04:08.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:297:0)
[03:04:08.748] Timer tick, asking Buildbucket for the build status
[03:04:08.914] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:08.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:298:0) after 1m0s
[03:05:08.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:298:0)
[03:05:08.939] Timer tick, asking Buildbucket for the build status
[03:05:09.353] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:09.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:299:0) after 1m0s
[03:06:09.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:299:0)
[03:06:09.423] Timer tick, asking Buildbucket for the build status
[03:06:09.512] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:09.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:300:0) after 1m0s
[03:07:09.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:300:0)
[03:07:09.640] Timer tick, asking Buildbucket for the build status
[03:07:09.718] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:09.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:301:0) after 1m0s
[03:08:09.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:301:0)
[03:08:09.747] Timer tick, asking Buildbucket for the build status
[03:08:10.107] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:10.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:302:0) after 1m0s
[03:09:10.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:302:0)
[03:09:10.141] Timer tick, asking Buildbucket for the build status
[03:09:10.241] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:10.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:303:0) after 1m0s
[03:10:10.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:303:0)
[03:10:10.345] Timer tick, asking Buildbucket for the build status
[03:10:10.640] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:10.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:304:0) after 1m0s
[03:11:10.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:304:0)
[03:11:10.835] Timer tick, asking Buildbucket for the build status
[03:11:11.018] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:11.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:305:0) after 1m0s
[03:12:11.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:305:0)
[03:12:11.153] Timer tick, asking Buildbucket for the build status
[03:12:11.231] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:11.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:306:0) after 1m0s
[03:13:11.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:306:0)
[03:13:11.324] Timer tick, asking Buildbucket for the build status
[03:13:11.417] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:11.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:307:0) after 1m0s
[03:14:11.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:307:0)
[03:14:11.507] Timer tick, asking Buildbucket for the build status
[03:14:11.770] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:11.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:308:0) after 1m0s
[03:15:11.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:308:0)
[03:15:11.881] Timer tick, asking Buildbucket for the build status
[03:15:11.967] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:11.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:309:0) after 1m0s
[03:16:12.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:309:0)
[03:16:12.071] Timer tick, asking Buildbucket for the build status
[03:16:12.139] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:12.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:310:0) after 1m0s
[03:17:12.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:310:0)
[03:17:12.180] Timer tick, asking Buildbucket for the build status
[03:17:12.258] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:12.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:311:0) after 1m0s
[03:18:12.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:311:0)
[03:18:12.415] Timer tick, asking Buildbucket for the build status
[03:18:12.536] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:12.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:312:0) after 1m0s
[03:19:12.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:312:0)
[03:19:12.624] Timer tick, asking Buildbucket for the build status
[03:19:12.704] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:12.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:313:0) after 1m0s
[03:20:12.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:313:0)
[03:20:12.786] Timer tick, asking Buildbucket for the build status
[03:20:12.874] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:314:0) after 1m0s
[03:21:13.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:314:0)
[03:21:13.078] Timer tick, asking Buildbucket for the build status
[03:21:13.164] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:13.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:315:0) after 1m0s
[03:22:13.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:315:0)
[03:22:13.303] Timer tick, asking Buildbucket for the build status
[03:22:13.596] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:13.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:316:0) after 1m0s
[03:23:13.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:316:0)
[03:23:13.649] Timer tick, asking Buildbucket for the build status
[03:23:13.732] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:13.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:317:0) after 1m0s
[03:24:13.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:317:0)
[03:24:13.751] Timer tick, asking Buildbucket for the build status
[03:24:14.225] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:14.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:318:0) after 1m0s
[03:25:14.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:318:0)
[03:25:14.705] Timer tick, asking Buildbucket for the build status
[03:25:14.790] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:14.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:319:0) after 1m0s
[03:26:14.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:319:0)
[03:26:14.968] Timer tick, asking Buildbucket for the build status
[03:26:15.041] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:15.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:320:0) after 1m0s
[03:27:15.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:320:0)
[03:27:15.086] Timer tick, asking Buildbucket for the build status
[03:27:15.401] Build 8855464863714278112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:15.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9020894926804758800:321:0) after 1m0s
[03:27:32.195] Received PubSub notification, asking Buildbucket for the build status
[03:27:32.356] Build 8855464863714278112: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:27:32.356] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1613186851536289",
    "created_by": "project:nacl",
    "created_ts": "1613167621845895",
    "failure_reason": "BUILD_FAILURE",
    "id": "8855464863714278112",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@376086a8d121c8bd2377ee67eb119ace555ed82e\", \"title\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/376086a8d121c8bd2377ee67eb119ace555ed82e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@376086a8d121c8bd2377ee67eb119ace555ed82e\", \"title\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/376086a8d121c8bd2377ee67eb119ace555ed82e\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-k2rh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5182, \"got_revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"376086a8d121c8bd2377ee67eb119ace555ed82e\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-k2rh\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1613179209437473",
    "status": "COMPLETED",
    "status_changed_ts": "1613179210184712",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5182",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/376086a8d121c8bd2377ee67eb119ace555ed82e",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/376086a8d121c8bd2377ee67eb119ace555ed82e",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9020894926804758800",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8855464863714278112/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:51b11a064b8d0510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1613186851794721",
    "url": "https://ci.chromium.org/b/8855464863714278112",
    "utcnow_ts": "1613186852311499"
  }
}
[03:27:32.356] Invocation finished in 5h20m32.841891488s with status FAILED