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

Status: FAILED

Triggered by: -

Duration: 4 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

d28e6c52e05ea2f1b3231de473b6dbfce59bce40 (4 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[20:04:24.301] New invocation is queued and will start shortly
[20:04:25.466] Starting the invocation (attempt 1)
[20:04:25.509] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:04:25.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:04:25.511] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9024073533146937680",
  "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\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\",\"title\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
  "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:9024073533146937680",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
    "gitiles_ref:refs/heads/master"
  ]
}
[20:04:27.599] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:nacl",
    "created_ts": "1610136265666619",
    "id": "8858643471051072608",
    "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\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1610136267281041",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5175",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9024073533146937680",
      "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/8858643471051072608/+/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": "1610136267281446",
    "url": "https://ci.chromium.org/b/8858643471051072608",
    "utcnow_ts": "1610136267585605"
  }
}
[20:04:27.599] Task URL: https://ci.chromium.org/b/8858643471051072608
[20:04:27.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:2:0) after 1m0s
[20:05:27.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:2:0)
[20:05:27.899] Timer tick, asking Buildbucket for the build status
[20:05:28.031] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:28.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:3:0) after 1m0s
[20:06:28.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:3:0)
[20:06:28.205] Timer tick, asking Buildbucket for the build status
[20:06:28.311] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:28.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:4:0) after 1m0s
[20:07:29.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:4:0)
[20:07:29.455] Timer tick, asking Buildbucket for the build status
[20:07:29.533] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:29.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:5:0) after 1m0s
[20:08:29.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:5:0)
[20:08:29.941] Timer tick, asking Buildbucket for the build status
[20:08:30.047] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:30.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:6:0) after 1m0s
[20:09:30.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:6:0)
[20:09:30.269] Timer tick, asking Buildbucket for the build status
[20:09:30.359] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:30.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:7:0) after 1m0s
[20:10:30.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:7:0)
[20:10:30.620] Timer tick, asking Buildbucket for the build status
[20:10:30.726] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:30.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:8:0) after 1m0s
[20:11:30.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:8:0)
[20:11:30.817] Timer tick, asking Buildbucket for the build status
[20:11:30.933] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:30.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:9:0) after 1m0s
[20:12:31.233] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:9:0)
[20:12:31.233] Timer tick, asking Buildbucket for the build status
[20:12:31.326] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:31.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:10:0) after 1m0s
[20:13:31.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:10:0)
[20:13:31.403] Timer tick, asking Buildbucket for the build status
[20:13:31.484] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:31.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:11:0) after 1m0s
[20:14:31.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:11:0)
[20:14:31.598] Timer tick, asking Buildbucket for the build status
[20:14:31.720] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:31.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:12:0) after 1m0s
[20:15:32.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:12:0)
[20:15:32.105] Timer tick, asking Buildbucket for the build status
[20:15:32.321] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:32.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:13:0) after 1m0s
[20:16:32.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:13:0)
[20:16:32.539] Timer tick, asking Buildbucket for the build status
[20:16:32.621] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:32.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:14:0) after 1m0s
[20:17:32.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:14:0)
[20:17:32.686] Timer tick, asking Buildbucket for the build status
[20:17:32.770] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:32.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:15:0) after 1m0s
[20:18:32.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:15:0)
[20:18:32.803] Timer tick, asking Buildbucket for the build status
[20:18:32.875] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:32.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:16:0) after 1m0s
[20:19:33.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:16:0)
[20:19:33.053] Timer tick, asking Buildbucket for the build status
[20:19:33.156] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:33.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:17:0) after 1m0s
[20:20:33.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:17:0)
[20:20:33.273] Timer tick, asking Buildbucket for the build status
[20:20:33.371] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:33.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:18:0) after 1m0s
[20:21:33.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:18:0)
[20:21:33.414] Timer tick, asking Buildbucket for the build status
[20:21:33.544] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:33.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:19:0) after 1m0s
[20:22:33.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:19:0)
[20:22:33.584] Timer tick, asking Buildbucket for the build status
[20:22:33.672] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:33.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:20:0) after 1m0s
[20:23:33.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:20:0)
[20:23:33.770] Timer tick, asking Buildbucket for the build status
[20:23:33.900] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:33.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:21:0) after 1m0s
[20:24:33.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:21:0)
[20:24:33.912] Timer tick, asking Buildbucket for the build status
[20:24:33.993] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:33.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:22:0) after 1m0s
[20:25:34.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:22:0)
[20:25:34.044] Timer tick, asking Buildbucket for the build status
[20:25:34.153] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:34.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:23:0) after 1m0s
[20:26:34.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:23:0)
[20:26:34.222] Timer tick, asking Buildbucket for the build status
[20:26:34.307] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:34.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:24:0) after 1m0s
[20:27:34.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:24:0)
[20:27:34.301] Timer tick, asking Buildbucket for the build status
[20:27:34.376] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:34.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:25:0) after 1m0s
[20:28:34.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:25:0)
[20:28:34.788] Timer tick, asking Buildbucket for the build status
[20:28:34.891] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:34.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:26:0) after 1m0s
[20:29:35.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:26:0)
[20:29:35.068] Timer tick, asking Buildbucket for the build status
[20:29:35.197] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:35.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:27:0) after 1m0s
[20:30:35.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:27:0)
[20:30:35.230] Timer tick, asking Buildbucket for the build status
[20:30:35.338] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:35.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:28:0) after 1m0s
[20:31:35.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:28:0)
[20:31:35.409] Timer tick, asking Buildbucket for the build status
[20:31:35.509] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:35.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:29:0) after 1m0s
[20:32:35.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:29:0)
[20:32:35.646] Timer tick, asking Buildbucket for the build status
[20:32:35.903] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:35.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:30:0) after 1m0s
[20:33:35.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:30:0)
[20:33:35.943] Timer tick, asking Buildbucket for the build status
[20:33:36.159] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:36.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:31:0) after 1m0s
[20:34:36.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:31:0)
[20:34:36.205] Timer tick, asking Buildbucket for the build status
[20:34:36.327] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:36.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:32:0) after 1m0s
[20:35:36.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:32:0)
[20:35:36.365] Timer tick, asking Buildbucket for the build status
[20:35:36.440] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:36.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:33:0) after 1m0s
[20:36:36.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:33:0)
[20:36:36.537] Timer tick, asking Buildbucket for the build status
[20:36:36.629] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:36.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:34:0) after 1m0s
[20:37:36.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:34:0)
[20:37:36.708] Timer tick, asking Buildbucket for the build status
[20:37:36.819] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:36.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:35:0) after 1m0s
[20:38:36.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:35:0)
[20:38:36.908] Timer tick, asking Buildbucket for the build status
[20:38:36.990] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:36.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:36:0) after 1m0s
[20:39:37.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:36:0)
[20:39:37.061] Timer tick, asking Buildbucket for the build status
[20:39:37.165] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:37.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:37:0) after 1m0s
[20:40:37.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:37:0)
[20:40:37.260] Timer tick, asking Buildbucket for the build status
[20:40:38.436] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:38.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:38:0) after 1m0s
[20:41:38.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:38:0)
[20:41:38.658] Timer tick, asking Buildbucket for the build status
[20:41:38.903] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:38.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:39:0) after 1m0s
[20:42:38.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:39:0)
[20:42:38.978] Timer tick, asking Buildbucket for the build status
[20:42:39.087] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:39.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:40:0) after 1m0s
[20:43:39.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:40:0)
[20:43:39.111] Timer tick, asking Buildbucket for the build status
[20:43:39.222] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:39.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:41:0) after 1m0s
[20:44:39.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:41:0)
[20:44:39.248] Timer tick, asking Buildbucket for the build status
[20:44:39.373] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:39.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:42:0) after 1m0s
[20:45:39.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:42:0)
[20:45:39.396] Timer tick, asking Buildbucket for the build status
[20:45:39.478] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:39.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:43:0) after 1m0s
[20:46:39.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:43:0)
[20:46:39.505] Timer tick, asking Buildbucket for the build status
[20:46:39.615] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:39.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:44:0) after 1m0s
[20:47:39.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:44:0)
[20:47:39.711] Timer tick, asking Buildbucket for the build status
[20:47:39.795] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:39.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:45:0) after 1m0s
[20:48:39.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:45:0)
[20:48:39.850] Timer tick, asking Buildbucket for the build status
[20:48:39.935] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:39.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:46:0) after 1m0s
[20:49:39.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:46:0)
[20:49:39.972] Timer tick, asking Buildbucket for the build status
[20:49:40.113] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:40.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:47:0) after 1m0s
[20:50:40.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:47:0)
[20:50:40.210] Timer tick, asking Buildbucket for the build status
[20:50:40.797] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:40.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:48:0) after 1m0s
[20:51:40.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:48:0)
[20:51:40.858] Timer tick, asking Buildbucket for the build status
[20:51:40.940] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:40.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:49:0) after 1m0s
[20:52:40.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:49:0)
[20:52:40.931] Timer tick, asking Buildbucket for the build status
[20:52:41.295] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:41.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:50:0) after 1m0s
[20:53:41.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:50:0)
[20:53:41.314] Timer tick, asking Buildbucket for the build status
[20:53:41.530] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:41.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:51:0) after 1m0s
[20:54:41.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:51:0)
[20:54:41.511] Timer tick, asking Buildbucket for the build status
[20:54:41.613] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:41.613] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:52:0) after 1m0s
[20:55:41.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:52:0)
[20:55:41.656] Timer tick, asking Buildbucket for the build status
[20:55:41.746] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:41.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:53:0) after 1m0s
[20:56:41.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:53:0)
[20:56:41.787] Timer tick, asking Buildbucket for the build status
[20:56:41.875] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:41.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:54:0) after 1m0s
[20:57:41.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:54:0)
[20:57:41.896] Timer tick, asking Buildbucket for the build status
[20:57:42.022] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:42.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:55:0) after 1m0s
[20:58:42.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:55:0)
[20:58:42.046] Timer tick, asking Buildbucket for the build status
[20:58:42.123] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:42.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:56:0) after 1m0s
[20:59:42.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:56:0)
[20:59:42.187] Timer tick, asking Buildbucket for the build status
[20:59:42.301] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:42.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:57:0) after 1m0s
[21:00:42.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:57:0)
[21:00:42.371] Timer tick, asking Buildbucket for the build status
[21:00:42.543] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:42.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:58:0) after 1m0s
[21:01:42.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:58:0)
[21:01:42.619] Timer tick, asking Buildbucket for the build status
[21:01:42.736] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:42.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:59:0) after 1m0s
[21:02:42.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:59:0)
[21:02:42.719] Timer tick, asking Buildbucket for the build status
[21:02:42.802] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:42.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:60:0) after 1m0s
[21:03:42.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:60:0)
[21:03:42.859] Timer tick, asking Buildbucket for the build status
[21:03:42.993] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:42.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:61:0) after 1m0s
[21:04:43.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:61:0)
[21:04:43.041] Timer tick, asking Buildbucket for the build status
[21:04:43.222] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:43.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:62:0) after 1m0s
[21:05:44.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:62:0)
[21:05:44.267] Timer tick, asking Buildbucket for the build status
[21:05:44.483] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:44.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:63:0) after 1m0s
[21:06:44.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:63:0)
[21:06:44.634] Timer tick, asking Buildbucket for the build status
[21:06:44.744] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:44.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:64:0) after 1m0s
[21:07:44.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:64:0)
[21:07:44.751] Timer tick, asking Buildbucket for the build status
[21:07:44.837] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:44.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:65:0) after 1m0s
[21:08:45.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:65:0)
[21:08:45.148] Timer tick, asking Buildbucket for the build status
[21:08:45.398] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:45.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:66:0) after 1m0s
[21:09:45.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:66:0)
[21:09:45.535] Timer tick, asking Buildbucket for the build status
[21:09:45.641] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:45.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:67:0) after 1m0s
[21:10:45.715] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:67:0)
[21:10:45.715] Timer tick, asking Buildbucket for the build status
[21:10:45.789] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:45.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:68:0) after 1m0s
[21:11:45.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:68:0)
[21:11:45.843] Timer tick, asking Buildbucket for the build status
[21:11:45.924] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:45.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:69:0) after 1m0s
[21:12:46.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:69:0)
[21:12:46.027] Timer tick, asking Buildbucket for the build status
[21:12:46.107] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:46.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:70:0) after 1m0s
[21:13:46.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:70:0)
[21:13:46.534] Timer tick, asking Buildbucket for the build status
[21:13:46.727] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:46.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:71:0) after 1m0s
[21:14:46.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:71:0)
[21:14:46.906] Timer tick, asking Buildbucket for the build status
[21:14:47.091] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:47.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:72:0) after 1m0s
[21:15:47.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:72:0)
[21:15:47.235] Timer tick, asking Buildbucket for the build status
[21:15:47.328] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:47.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:73:0) after 1m0s
[21:16:47.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:73:0)
[21:16:47.550] Timer tick, asking Buildbucket for the build status
[21:16:47.631] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:47.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:74:0) after 1m0s
[21:17:47.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:74:0)
[21:17:47.750] Timer tick, asking Buildbucket for the build status
[21:17:48.013] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:48.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:75:0) after 1m0s
[21:18:48.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:75:0)
[21:18:48.324] Timer tick, asking Buildbucket for the build status
[21:18:48.406] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:48.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:76:0) after 1m0s
[21:19:48.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:76:0)
[21:19:48.579] Timer tick, asking Buildbucket for the build status
[21:19:48.693] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:48.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:77:0) after 1m0s
[21:20:48.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:77:0)
[21:20:48.869] Timer tick, asking Buildbucket for the build status
[21:20:48.929] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:48.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:78:0) after 1m0s
[21:21:49.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:78:0)
[21:21:49.066] Timer tick, asking Buildbucket for the build status
[21:21:49.152] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:49.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:79:0) after 1m0s
[21:22:49.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:79:0)
[21:22:49.341] Timer tick, asking Buildbucket for the build status
[21:22:50.477] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:50.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:80:0) after 1m0s
[21:23:50.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:80:0)
[21:23:50.506] Timer tick, asking Buildbucket for the build status
[21:23:50.587] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:50.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:81:0) after 1m0s
[21:24:50.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:81:0)
[21:24:50.610] Timer tick, asking Buildbucket for the build status
[21:24:51.018] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:51.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:82:0) after 1m0s
[21:25:51.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:82:0)
[21:25:51.199] Timer tick, asking Buildbucket for the build status
[21:25:51.269] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:51.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:83:0) after 1m0s
[21:26:51.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:83:0)
[21:26:51.507] Timer tick, asking Buildbucket for the build status
[21:26:51.582] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:51.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:84:0) after 1m0s
[21:27:51.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:84:0)
[21:27:51.747] Timer tick, asking Buildbucket for the build status
[21:27:51.834] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:51.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:85:0) after 1m0s
[21:28:51.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:85:0)
[21:28:51.882] Timer tick, asking Buildbucket for the build status
[21:28:52.087] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:52.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:86:0) after 1m0s
[21:29:52.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:86:0)
[21:29:52.164] Timer tick, asking Buildbucket for the build status
[21:29:54.125] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:54.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:87:0) after 1m0s
[21:30:54.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:87:0)
[21:30:54.290] Timer tick, asking Buildbucket for the build status
[21:30:54.405] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:54.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:88:0) after 1m0s
[21:31:54.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:88:0)
[21:31:54.507] Timer tick, asking Buildbucket for the build status
[21:31:54.632] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:54.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:89:0) after 1m0s
[21:32:54.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:89:0)
[21:32:54.719] Timer tick, asking Buildbucket for the build status
[21:32:54.806] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:54.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:90:0) after 1m0s
[21:33:55.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:90:0)
[21:33:55.039] Timer tick, asking Buildbucket for the build status
[21:33:55.156] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:55.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:91:0) after 1m0s
[21:34:55.315] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:91:0)
[21:34:55.315] Timer tick, asking Buildbucket for the build status
[21:34:55.477] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:55.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:92:0) after 1m0s
[21:35:55.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:92:0)
[21:35:55.493] Timer tick, asking Buildbucket for the build status
[21:35:55.606] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:55.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:93:0) after 1m0s
[21:36:55.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:93:0)
[21:36:55.682] Timer tick, asking Buildbucket for the build status
[21:36:55.799] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:55.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:94:0) after 1m0s
[21:37:55.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:94:0)
[21:37:55.895] Timer tick, asking Buildbucket for the build status
[21:37:56.203] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:56.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:95:0) after 1m0s
[21:38:56.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:95:0)
[21:38:56.259] Timer tick, asking Buildbucket for the build status
[21:38:56.348] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:56.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:96:0) after 1m0s
[21:39:56.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:96:0)
[21:39:56.382] Timer tick, asking Buildbucket for the build status
[21:39:56.467] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:56.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:97:0) after 1m0s
[21:40:56.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:97:0)
[21:40:56.582] Timer tick, asking Buildbucket for the build status
[21:40:56.893] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:56.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:98:0) after 1m0s
[21:41:56.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:98:0)
[21:41:56.916] Timer tick, asking Buildbucket for the build status
[21:41:57.045] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:57.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:99:0) after 1m0s
[21:42:57.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:99:0)
[21:42:57.088] Timer tick, asking Buildbucket for the build status
[21:42:57.191] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:57.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:100:0) after 1m0s
[21:43:57.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:100:0)
[21:43:57.227] Timer tick, asking Buildbucket for the build status
[21:43:57.334] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:57.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:101:0) after 1m0s
[21:44:57.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:101:0)
[21:44:57.394] Timer tick, asking Buildbucket for the build status
[21:44:57.717] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:57.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:102:0) after 1m0s
[21:45:57.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:102:0)
[21:45:57.849] Timer tick, asking Buildbucket for the build status
[21:45:57.934] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:57.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:103:0) after 1m0s
[21:46:57.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:103:0)
[21:46:57.984] Timer tick, asking Buildbucket for the build status
[21:46:58.071] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:58.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:104:0) after 1m0s
[21:47:58.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:104:0)
[21:47:58.186] Timer tick, asking Buildbucket for the build status
[21:47:58.257] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:58.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:105:0) after 1m0s
[21:48:58.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:105:0)
[21:48:58.322] Timer tick, asking Buildbucket for the build status
[21:48:58.406] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:58.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:106:0) after 1m0s
[21:49:58.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:106:0)
[21:49:58.452] Timer tick, asking Buildbucket for the build status
[21:49:58.593] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:58.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:107:0) after 1m0s
[21:50:58.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:107:0)
[21:50:58.570] Timer tick, asking Buildbucket for the build status
[21:50:58.698] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:58.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:108:0) after 1m0s
[21:51:58.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:108:0)
[21:51:58.689] Timer tick, asking Buildbucket for the build status
[21:51:58.762] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:58.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:109:0) after 1m0s
[21:52:58.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:109:0)
[21:52:58.799] Timer tick, asking Buildbucket for the build status
[21:52:58.897] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:58.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:110:0) after 1m0s
[21:53:59.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:110:0)
[21:53:59.053] Timer tick, asking Buildbucket for the build status
[21:53:59.204] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:59.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:111:0) after 1m0s
[21:54:59.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:111:0)
[21:54:59.235] Timer tick, asking Buildbucket for the build status
[21:54:59.377] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:59.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:112:0) after 1m0s
[21:55:59.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:112:0)
[21:55:59.441] Timer tick, asking Buildbucket for the build status
[21:55:59.857] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:59.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:113:0) after 1m0s
[21:56:59.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:113:0)
[21:56:59.896] Timer tick, asking Buildbucket for the build status
[21:57:00.183] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:00.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:114:0) after 1m0s
[21:58:00.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:114:0)
[21:58:00.195] Timer tick, asking Buildbucket for the build status
[21:58:00.445] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:00.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:115:0) after 1m0s
[21:59:00.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:115:0)
[21:59:00.469] Timer tick, asking Buildbucket for the build status
[21:59:00.556] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:00.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:116:0) after 1m0s
[22:00:00.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:116:0)
[22:00:00.584] Timer tick, asking Buildbucket for the build status
[22:00:00.739] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:00.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:117:0) after 1m0s
[22:01:01.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:117:0)
[22:01:01.580] Timer tick, asking Buildbucket for the build status
[22:01:01.922] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:01.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:118:0) after 1m0s
[22:02:02.300] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:118:0)
[22:02:02.300] Timer tick, asking Buildbucket for the build status
[22:02:02.400] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:02.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:119:0) after 1m0s
[22:03:02.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:119:0)
[22:03:02.819] Timer tick, asking Buildbucket for the build status
[22:03:02.920] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:02.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:120:0) after 1m0s
[22:04:03.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:120:0)
[22:04:03.026] Timer tick, asking Buildbucket for the build status
[22:04:03.560] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:03.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:121:0) after 1m0s
[22:05:03.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:121:0)
[22:05:03.599] Timer tick, asking Buildbucket for the build status
[22:05:04.006] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:04.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:122:0) after 1m0s
[22:06:04.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:122:0)
[22:06:04.037] Timer tick, asking Buildbucket for the build status
[22:06:04.165] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:04.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:123:0) after 1m0s
[22:07:04.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:123:0)
[22:07:04.212] Timer tick, asking Buildbucket for the build status
[22:07:04.501] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:04.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:124:0) after 1m0s
[22:08:04.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:124:0)
[22:08:04.534] Timer tick, asking Buildbucket for the build status
[22:08:04.909] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:04.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:125:0) after 1m0s
[22:09:04.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:125:0)
[22:09:04.937] Timer tick, asking Buildbucket for the build status
[22:09:05.051] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:05.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:126:0) after 1m0s
[22:10:05.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:126:0)
[22:10:05.072] Timer tick, asking Buildbucket for the build status
[22:10:05.416] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:05.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:127:0) after 1m0s
[22:11:05.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:127:0)
[22:11:05.398] Timer tick, asking Buildbucket for the build status
[22:11:05.533] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:05.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:128:0) after 1m0s
[22:12:05.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:128:0)
[22:12:05.548] Timer tick, asking Buildbucket for the build status
[22:12:05.882] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:05.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:129:0) after 1m0s
[22:13:05.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:129:0)
[22:13:05.923] Timer tick, asking Buildbucket for the build status
[22:13:06.147] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:06.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:130:0) after 1m0s
[22:14:06.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:130:0)
[22:14:06.227] Timer tick, asking Buildbucket for the build status
[22:14:06.420] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:06.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:131:0) after 1m0s
[22:15:06.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:131:0)
[22:15:06.455] Timer tick, asking Buildbucket for the build status
[22:15:06.587] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:06.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:132:0) after 1m0s
[22:16:06.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:132:0)
[22:16:06.591] Timer tick, asking Buildbucket for the build status
[22:16:06.678] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:06.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:133:0) after 1m0s
[22:17:06.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:133:0)
[22:17:06.737] Timer tick, asking Buildbucket for the build status
[22:17:07.224] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:07.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:134:0) after 1m0s
[22:18:07.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:134:0)
[22:18:07.251] Timer tick, asking Buildbucket for the build status
[22:18:07.395] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:07.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:135:0) after 1m0s
[22:19:07.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:135:0)
[22:19:07.415] Timer tick, asking Buildbucket for the build status
[22:19:07.494] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:07.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:136:0) after 1m0s
[22:20:07.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:136:0)
[22:20:07.578] Timer tick, asking Buildbucket for the build status
[22:20:07.722] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:07.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:137:0) after 1m0s
[22:21:07.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:137:0)
[22:21:07.761] Timer tick, asking Buildbucket for the build status
[22:21:07.878] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:07.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:138:0) after 1m0s
[22:22:07.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:138:0)
[22:22:07.990] Timer tick, asking Buildbucket for the build status
[22:22:08.129] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:08.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:139:0) after 1m0s
[22:23:08.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:139:0)
[22:23:08.192] Timer tick, asking Buildbucket for the build status
[22:23:08.279] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:08.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:140:0) after 1m0s
[22:24:08.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:140:0)
[22:24:08.339] Timer tick, asking Buildbucket for the build status
[22:24:08.485] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:08.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:141:0) after 1m0s
[22:25:08.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:141:0)
[22:25:08.504] Timer tick, asking Buildbucket for the build status
[22:25:08.647] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:08.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:142:0) after 1m0s
[22:26:08.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:142:0)
[22:26:08.673] Timer tick, asking Buildbucket for the build status
[22:26:08.774] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:08.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:143:0) after 1m0s
[22:27:08.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:143:0)
[22:27:08.829] Timer tick, asking Buildbucket for the build status
[22:27:08.921] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:08.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:144:0) after 1m0s
[22:28:08.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:144:0)
[22:28:08.966] Timer tick, asking Buildbucket for the build status
[22:28:09.077] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:09.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:145:0) after 1m0s
[22:29:09.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:145:0)
[22:29:09.105] Timer tick, asking Buildbucket for the build status
[22:29:09.229] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:09.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:146:0) after 1m0s
[22:30:09.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:146:0)
[22:30:09.263] Timer tick, asking Buildbucket for the build status
[22:30:09.383] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:09.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:147:0) after 1m0s
[22:31:09.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:147:0)
[22:31:09.415] Timer tick, asking Buildbucket for the build status
[22:31:09.607] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:09.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:148:0) after 1m0s
[22:32:09.660] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:148:0)
[22:32:09.660] Timer tick, asking Buildbucket for the build status
[22:32:09.762] Build 8858643471051072608: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:09.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:149:0) after 1m0s
[22:33:01.070] Received PubSub notification, asking Buildbucket for the build status
[22:33:01.182] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:09.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:149:0)
[22:33:09.806] Timer tick, asking Buildbucket for the build status
[22:33:09.899] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:09.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:151:0) after 1m0s
[22:34:09.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:151:0)
[22:34:09.912] Timer tick, asking Buildbucket for the build status
[22:34:10.771] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:10.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:152:0) after 1m0s
[22:35:10.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:152:0)
[22:35:10.860] Timer tick, asking Buildbucket for the build status
[22:35:10.975] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:10.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:153:0) after 1m0s
[22:36:11.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:153:0)
[22:36:11.002] Timer tick, asking Buildbucket for the build status
[22:36:11.132] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:11.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:154:0) after 1m0s
[22:37:11.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:154:0)
[22:37:11.192] Timer tick, asking Buildbucket for the build status
[22:37:11.311] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:11.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:155:0) after 1m0s
[22:38:11.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:155:0)
[22:38:11.368] Timer tick, asking Buildbucket for the build status
[22:38:11.472] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:11.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:156:0) after 1m0s
[22:39:11.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:156:0)
[22:39:11.468] Timer tick, asking Buildbucket for the build status
[22:39:11.608] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:11.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:157:0) after 1m0s
[22:40:11.632] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:157:0)
[22:40:11.632] Timer tick, asking Buildbucket for the build status
[22:40:11.773] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:11.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:158:0) after 1m0s
[22:41:11.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:158:0)
[22:41:11.812] Timer tick, asking Buildbucket for the build status
[22:41:11.876] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:11.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:159:0) after 1m0s
[22:42:11.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:159:0)
[22:42:11.891] Timer tick, asking Buildbucket for the build status
[22:42:12.113] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:12.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:160:0) after 1m0s
[22:43:12.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:160:0)
[22:43:12.536] Timer tick, asking Buildbucket for the build status
[22:43:12.590] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:12.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:161:0) after 1m0s
[22:44:12.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:161:0)
[22:44:12.626] Timer tick, asking Buildbucket for the build status
[22:44:12.713] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:12.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:162:0) after 1m0s
[22:45:13.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:162:0)
[22:45:13.053] Timer tick, asking Buildbucket for the build status
[22:45:13.222] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:13.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:163:0) after 1m0s
[22:46:13.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:163:0)
[22:46:13.398] Timer tick, asking Buildbucket for the build status
[22:46:13.601] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:13.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:164:0) after 1m0s
[22:47:13.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:164:0)
[22:47:13.670] Timer tick, asking Buildbucket for the build status
[22:47:14.052] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:14.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:165:0) after 1m0s
[22:48:14.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:165:0)
[22:48:14.115] Timer tick, asking Buildbucket for the build status
[22:48:14.555] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:14.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:166:0) after 1m0s
[22:49:14.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:166:0)
[22:49:14.707] Timer tick, asking Buildbucket for the build status
[22:49:14.932] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:14.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:167:0) after 1m0s
[22:50:15.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:167:0)
[22:50:15.023] Timer tick, asking Buildbucket for the build status
[22:50:15.189] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:15.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:168:0) after 1m0s
[22:51:15.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:168:0)
[22:51:15.398] Timer tick, asking Buildbucket for the build status
[22:51:15.948] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:15.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:169:0) after 1m0s
[22:52:16.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:169:0)
[22:52:16.059] Timer tick, asking Buildbucket for the build status
[22:52:16.404] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:16.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:170:0) after 1m0s
[22:53:16.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:170:0)
[22:53:16.476] Timer tick, asking Buildbucket for the build status
[22:53:16.646] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:16.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:171:0) after 1m0s
[22:54:16.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:171:0)
[22:54:16.713] Timer tick, asking Buildbucket for the build status
[22:54:18.326] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:18.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:172:0) after 1m0s
[22:55:18.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:172:0)
[22:55:18.353] Timer tick, asking Buildbucket for the build status
[22:55:18.674] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:18.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:173:0) after 1m0s
[22:56:18.765] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:173:0)
[22:56:18.765] Timer tick, asking Buildbucket for the build status
[22:56:18.938] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:18.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:174:0) after 1m0s
[22:57:19.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:174:0)
[22:57:19.011] Timer tick, asking Buildbucket for the build status
[22:57:19.156] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:19.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:175:0) after 1m0s
[22:58:19.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:175:0)
[22:58:19.408] Timer tick, asking Buildbucket for the build status
[22:58:19.535] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:19.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:176:0) after 1m0s
[22:59:19.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:176:0)
[22:59:19.699] Timer tick, asking Buildbucket for the build status
[22:59:19.971] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:19.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:177:0) after 1m0s
[23:00:20.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:177:0)
[23:00:20.049] Timer tick, asking Buildbucket for the build status
[23:00:20.439] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:20.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:178:0) after 1m0s
[23:01:20.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:178:0)
[23:01:20.417] Timer tick, asking Buildbucket for the build status
[23:01:20.494] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:20.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:179:0) after 1m0s
[23:02:20.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:179:0)
[23:02:20.534] Timer tick, asking Buildbucket for the build status
[23:02:20.907] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:20.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:180:0) after 1m0s
[23:03:20.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:180:0)
[23:03:20.989] Timer tick, asking Buildbucket for the build status
[23:03:21.086] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:21.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:181:0) after 1m0s
[23:04:21.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:181:0)
[23:04:21.166] Timer tick, asking Buildbucket for the build status
[23:04:21.397] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:182:0) after 1m0s
[23:05:21.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:182:0)
[23:05:21.508] Timer tick, asking Buildbucket for the build status
[23:05:21.622] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:21.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:183:0) after 1m0s
[23:06:21.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:183:0)
[23:06:21.824] Timer tick, asking Buildbucket for the build status
[23:06:21.935] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:21.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:184:0) after 1m0s
[23:07:22.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:184:0)
[23:07:22.083] Timer tick, asking Buildbucket for the build status
[23:07:22.195] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:185:0) after 1m0s
[23:08:22.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:185:0)
[23:08:22.262] Timer tick, asking Buildbucket for the build status
[23:08:22.346] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:22.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:186:0) after 1m0s
[23:09:22.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:186:0)
[23:09:22.492] Timer tick, asking Buildbucket for the build status
[23:09:22.619] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:22.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:187:0) after 1m0s
[23:10:22.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:187:0)
[23:10:22.838] Timer tick, asking Buildbucket for the build status
[23:10:22.936] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:22.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:188:0) after 1m0s
[23:11:23.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:188:0)
[23:11:23.125] Timer tick, asking Buildbucket for the build status
[23:11:23.216] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:23.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:189:0) after 1m0s
[23:12:23.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:189:0)
[23:12:23.353] Timer tick, asking Buildbucket for the build status
[23:12:23.526] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:23.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:190:0) after 1m0s
[23:13:23.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:190:0)
[23:13:23.553] Timer tick, asking Buildbucket for the build status
[23:13:23.641] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:23.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:191:0) after 1m0s
[23:14:23.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:191:0)
[23:14:23.767] Timer tick, asking Buildbucket for the build status
[23:14:23.859] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:23.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:192:0) after 1m0s
[23:15:23.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:192:0)
[23:15:23.904] Timer tick, asking Buildbucket for the build status
[23:15:24.418] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:24.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:193:0) after 1m0s
[23:16:24.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:193:0)
[23:16:24.448] Timer tick, asking Buildbucket for the build status
[23:16:25.038] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:25.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:194:0) after 1m0s
[23:17:25.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:194:0)
[23:17:25.119] Timer tick, asking Buildbucket for the build status
[23:17:25.189] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:25.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:195:0) after 1m0s
[23:18:25.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:195:0)
[23:18:25.247] Timer tick, asking Buildbucket for the build status
[23:18:25.327] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:25.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:196:0) after 1m0s
[23:19:25.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:196:0)
[23:19:25.332] Timer tick, asking Buildbucket for the build status
[23:19:25.484] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:25.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:197:0) after 1m0s
[23:20:25.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:197:0)
[23:20:25.516] Timer tick, asking Buildbucket for the build status
[23:20:25.718] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:25.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:198:0) after 1m0s
[23:21:25.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:198:0)
[23:21:25.729] Timer tick, asking Buildbucket for the build status
[23:21:25.828] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:25.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:199:0) after 1m0s
[23:22:25.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:199:0)
[23:22:25.804] Timer tick, asking Buildbucket for the build status
[23:22:25.900] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:25.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:200:0) after 1m0s
[23:23:25.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:200:0)
[23:23:25.943] Timer tick, asking Buildbucket for the build status
[23:23:26.047] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:26.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:201:0) after 1m0s
[23:24:26.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:201:0)
[23:24:26.103] Timer tick, asking Buildbucket for the build status
[23:24:26.561] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:26.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:202:0) after 1m0s
[23:25:26.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:202:0)
[23:25:26.554] Timer tick, asking Buildbucket for the build status
[23:25:26.694] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:26.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:203:0) after 1m0s
[23:26:26.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:203:0)
[23:26:26.799] Timer tick, asking Buildbucket for the build status
[23:26:26.886] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:26.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:204:0) after 1m0s
[23:27:27.080] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:204:0)
[23:27:27.080] Timer tick, asking Buildbucket for the build status
[23:27:27.152] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:27.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:205:0) after 1m0s
[23:28:27.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:205:0)
[23:28:27.162] Timer tick, asking Buildbucket for the build status
[23:28:27.278] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:27.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:206:0) after 1m0s
[23:29:27.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:206:0)
[23:29:27.286] Timer tick, asking Buildbucket for the build status
[23:29:27.342] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:27.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:207:0) after 1m0s
[23:30:27.370] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:207:0)
[23:30:27.370] Timer tick, asking Buildbucket for the build status
[23:30:27.471] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:27.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:208:0) after 1m0s
[23:31:27.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:208:0)
[23:31:27.493] Timer tick, asking Buildbucket for the build status
[23:31:27.581] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:27.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:209:0) after 1m0s
[23:32:27.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:209:0)
[23:32:27.645] Timer tick, asking Buildbucket for the build status
[23:32:27.741] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:27.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:210:0) after 1m0s
[23:33:27.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:210:0)
[23:33:27.803] Timer tick, asking Buildbucket for the build status
[23:33:27.885] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:27.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:211:0) after 1m0s
[23:34:27.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:211:0)
[23:34:27.951] Timer tick, asking Buildbucket for the build status
[23:34:28.031] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:28.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:212:0) after 1m0s
[23:35:28.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:212:0)
[23:35:28.077] Timer tick, asking Buildbucket for the build status
[23:35:28.195] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:28.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:213:0) after 1m0s
[23:36:28.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:213:0)
[23:36:28.265] Timer tick, asking Buildbucket for the build status
[23:36:28.343] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:28.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:214:0) after 1m0s
[23:37:28.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:214:0)
[23:37:28.366] Timer tick, asking Buildbucket for the build status
[23:37:28.434] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:28.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:215:0) after 1m0s
[23:38:28.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:215:0)
[23:38:28.566] Timer tick, asking Buildbucket for the build status
[23:38:28.727] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:28.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:216:0) after 1m0s
[23:39:28.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:216:0)
[23:39:28.794] Timer tick, asking Buildbucket for the build status
[23:39:28.864] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:28.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:217:0) after 1m0s
[23:40:28.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:217:0)
[23:40:28.941] Timer tick, asking Buildbucket for the build status
[23:40:29.055] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:29.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:218:0) after 1m0s
[23:41:29.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:218:0)
[23:41:29.109] Timer tick, asking Buildbucket for the build status
[23:41:29.353] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:29.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:219:0) after 1m0s
[23:42:29.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:219:0)
[23:42:29.378] Timer tick, asking Buildbucket for the build status
[23:42:30.290] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:30.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:220:0) after 1m0s
[23:43:30.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:220:0)
[23:43:30.309] Timer tick, asking Buildbucket for the build status
[23:43:30.377] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:30.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:221:0) after 1m0s
[23:44:30.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:221:0)
[23:44:30.438] Timer tick, asking Buildbucket for the build status
[23:44:30.493] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:30.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:222:0) after 1m0s
[23:45:30.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:222:0)
[23:45:30.588] Timer tick, asking Buildbucket for the build status
[23:45:30.830] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:30.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:223:0) after 1m0s
[23:46:30.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:223:0)
[23:46:30.881] Timer tick, asking Buildbucket for the build status
[23:46:30.960] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:30.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:224:0) after 1m0s
[23:47:31.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:224:0)
[23:47:31.030] Timer tick, asking Buildbucket for the build status
[23:47:31.111] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:31.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:225:0) after 1m0s
[23:48:31.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:225:0)
[23:48:31.219] Timer tick, asking Buildbucket for the build status
[23:48:31.381] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:31.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:226:0) after 1m0s
[23:49:31.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:226:0)
[23:49:31.408] Timer tick, asking Buildbucket for the build status
[23:49:31.608] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:31.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:227:0) after 1m0s
[23:50:31.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:227:0)
[23:50:31.659] Timer tick, asking Buildbucket for the build status
[23:50:31.783] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:31.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:228:0) after 1m0s
[23:51:31.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:228:0)
[23:51:31.783] Timer tick, asking Buildbucket for the build status
[23:51:31.849] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:31.849] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:229:0) after 1m0s
[23:52:31.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:229:0)
[23:52:31.908] Timer tick, asking Buildbucket for the build status
[23:52:32.043] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:32.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:230:0) after 1m0s
[23:53:32.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:230:0)
[23:53:32.068] Timer tick, asking Buildbucket for the build status
[23:53:32.132] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:32.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:231:0) after 1m0s
[23:54:32.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:231:0)
[23:54:32.183] Timer tick, asking Buildbucket for the build status
[23:54:32.495] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:32.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:232:0) after 1m0s
[23:55:32.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:232:0)
[23:55:32.488] Timer tick, asking Buildbucket for the build status
[23:55:32.630] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:32.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:233:0) after 1m0s
[23:56:32.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:233:0)
[23:56:32.713] Timer tick, asking Buildbucket for the build status
[23:56:32.787] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:32.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:234:0) after 1m0s
[23:57:32.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:234:0)
[23:57:32.813] Timer tick, asking Buildbucket for the build status
[23:57:32.884] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:32.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:235:0) after 1m0s
[23:58:32.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:235:0)
[23:58:32.991] Timer tick, asking Buildbucket for the build status
[23:58:33.044] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:33.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:236:0) after 1m0s
[23:59:33.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:236:0)
[23:59:33.120] Timer tick, asking Buildbucket for the build status
[23:59:33.199] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:33.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:237:0) after 1m0s
[00:00:33.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:237:0)
[00:00:33.228] Timer tick, asking Buildbucket for the build status
[00:00:33.360] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:33.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:238:0) after 1m0s
[00:01:33.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:238:0)
[00:01:33.363] Timer tick, asking Buildbucket for the build status
[00:01:33.441] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:33.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:239:0) after 1m0s
[00:02:33.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:239:0)
[00:02:33.423] Timer tick, asking Buildbucket for the build status
[00:02:33.498] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:33.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:240:0) after 1m0s
[00:03:33.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:240:0)
[00:03:33.526] Timer tick, asking Buildbucket for the build status
[00:03:33.602] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:33.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:241:0) after 1m0s
[00:04:33.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:241:0)
[00:04:33.633] Timer tick, asking Buildbucket for the build status
[00:04:33.708] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:33.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:242:0) after 1m0s
[00:05:33.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:242:0)
[00:05:33.759] Timer tick, asking Buildbucket for the build status
[00:05:33.883] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:33.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:243:0) after 1m0s
[00:06:33.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:243:0)
[00:06:33.938] Timer tick, asking Buildbucket for the build status
[00:06:34.026] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:34.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:244:0) after 1m0s
[00:07:34.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:244:0)
[00:07:34.058] Timer tick, asking Buildbucket for the build status
[00:07:34.179] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:245:0) after 1m0s
[00:08:34.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:245:0)
[00:08:34.155] Timer tick, asking Buildbucket for the build status
[00:08:34.306] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:34.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:246:0) after 1m0s
[00:09:34.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:246:0)
[00:09:34.378] Timer tick, asking Buildbucket for the build status
[00:09:34.548] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:34.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:247:0) after 1m0s
[00:10:34.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:247:0)
[00:10:34.545] Timer tick, asking Buildbucket for the build status
[00:10:34.901] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:34.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:248:0) after 1m0s
[00:11:34.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:248:0)
[00:11:34.930] Timer tick, asking Buildbucket for the build status
[00:11:34.995] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:34.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:249:0) after 1m0s
[00:12:35.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:249:0)
[00:12:35.045] Timer tick, asking Buildbucket for the build status
[00:12:35.115] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:35.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:250:0) after 1m0s
[00:13:35.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:250:0)
[00:13:35.152] Timer tick, asking Buildbucket for the build status
[00:13:35.550] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:35.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:251:0) after 1m0s
[00:14:35.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:251:0)
[00:14:35.619] Timer tick, asking Buildbucket for the build status
[00:14:35.745] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:35.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:252:0) after 1m0s
[00:15:35.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:252:0)
[00:15:35.816] Timer tick, asking Buildbucket for the build status
[00:15:35.928] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:35.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:253:0) after 1m0s
[00:16:35.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:253:0)
[00:16:35.916] Timer tick, asking Buildbucket for the build status
[00:16:35.977] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:35.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:254:0) after 1m0s
[00:17:36.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:254:0)
[00:17:36.029] Timer tick, asking Buildbucket for the build status
[00:17:36.105] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:36.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:255:0) after 1m0s
[00:18:36.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:255:0)
[00:18:36.186] Timer tick, asking Buildbucket for the build status
[00:18:36.291] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:36.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:256:0) after 1m0s
[00:19:36.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:256:0)
[00:19:36.376] Timer tick, asking Buildbucket for the build status
[00:19:36.438] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:36.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:257:0) after 1m0s
[00:20:36.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:257:0)
[00:20:36.513] Timer tick, asking Buildbucket for the build status
[00:20:36.619] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:36.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:258:0) after 1m0s
[00:21:36.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:258:0)
[00:21:36.665] Timer tick, asking Buildbucket for the build status
[00:21:36.948] Build 8858643471051072608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:36.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9024073533146937680:259:0) after 1m0s
[00:21:54.937] Received PubSub notification, asking Buildbucket for the build status
[00:21:55.008] Build 8858643471051072608: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:21:55.008] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1610151713681351",
    "created_by": "project:nacl",
    "created_ts": "1610136265666619",
    "failure_reason": "BUILD_FAILURE",
    "id": "8858643471051072608",
    "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\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}}",
    "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\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"title\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-3lde\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5175, \"got_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"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-0-3lde\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5657-0417a3f\"], \"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": "1610145180135168",
    "status": "COMPLETED",
    "status_changed_ts": "1610145180697191",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5175",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d28e6c52e05ea2f1b3231de473b6dbfce59bce40",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9024073533146937680",
      "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/8858643471051072608/+/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:50fc6b3a74c0c310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1610151714204437",
    "url": "https://ci.chromium.org/b/8858643471051072608",
    "utcnow_ts": "1610151714997556"
  }
}
[00:21:55.008] Invocation finished in 4h17m30.724223687s with status FAILED