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

• 69a0d6e8affc94187af10e0673592d1b238c6eb0 (4 years ago)

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

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

[22:48:44.738] New invocation is queued and will start shortly
[22:48:45.866] Starting the invocation (attempt 1)
[22:48:45.914] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:48:45.914] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:48:45.914] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9035025426664820128",
  "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\":\"69a0d6e8affc94187af10e0673592d1b238c6eb0\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@69a0d6e8affc94187af10e0673592d1b238c6eb0\",\"title\":\"69a0d6e8affc94187af10e0673592d1b238c6eb0\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/69a0d6e8affc94187af10e0673592d1b238c6eb0\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}}",
  "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:9035025426664820128",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/69a0d6e8affc94187af10e0673592d1b238c6eb0",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/69a0d6e8affc94187af10e0673592d1b238c6eb0",
    "gitiles_ref:refs/heads/master"
  ]
}
[22:48:49.451] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1599691726107442",
    "id": "8869595364564209424",
    "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\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"title\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/69a0d6e8affc94187af10e0673592d1b238c6eb0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1599691728934801",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5168",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/69a0d6e8affc94187af10e0673592d1b238c6eb0",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/69a0d6e8affc94187af10e0673592d1b238c6eb0",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9035025426664820128",
      "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/8869595364564209424/+/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": "1599691728934935",
    "url": "https://ci.chromium.org/b/8869595364564209424",
    "utcnow_ts": "1599691729401236"
  }
}
[22:48:49.451] Task URL: https://ci.chromium.org/b/8869595364564209424
[22:48:49.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:2:0) after 1m0s
[22:49:49.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:2:0)
[22:49:49.654] Timer tick, asking Buildbucket for the build status
[22:49:49.789] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:49.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:3:0) after 1m0s
[22:50:50.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:3:0)
[22:50:50.046] Timer tick, asking Buildbucket for the build status
[22:50:50.130] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:50.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:4:0) after 1m0s
[22:51:50.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:4:0)
[22:51:50.852] Timer tick, asking Buildbucket for the build status
[22:51:50.935] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:50.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:5:0) after 1m0s
[22:52:51.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:5:0)
[22:52:51.043] Timer tick, asking Buildbucket for the build status
[22:52:51.200] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:51.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:6:0) after 1m0s
[22:53:52.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:6:0)
[22:53:52.452] Timer tick, asking Buildbucket for the build status
[22:53:52.589] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:52.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:7:0) after 1m0s
[22:54:52.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:7:0)
[22:54:52.856] Timer tick, asking Buildbucket for the build status
[22:54:52.975] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:52.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:8:0) after 1m0s
[22:55:53.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:8:0)
[22:55:53.211] Timer tick, asking Buildbucket for the build status
[22:55:53.295] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:53.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:9:0) after 1m0s
[22:56:53.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:9:0)
[22:56:53.382] Timer tick, asking Buildbucket for the build status
[22:56:53.693] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:53.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:10:0) after 1m0s
[22:57:53.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:10:0)
[22:57:53.968] Timer tick, asking Buildbucket for the build status
[22:57:54.045] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:54.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:11:0) after 1m0s
[22:58:54.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:11:0)
[22:58:54.226] Timer tick, asking Buildbucket for the build status
[22:58:54.364] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:54.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:12:0) after 1m0s
[22:59:54.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:12:0)
[22:59:54.404] Timer tick, asking Buildbucket for the build status
[22:59:55.634] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:55.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:13:0) after 1m0s
[23:00:55.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:13:0)
[23:00:55.924] Timer tick, asking Buildbucket for the build status
[23:00:56.047] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:56.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:14:0) after 1m0s
[23:01:56.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:14:0)
[23:01:56.067] Timer tick, asking Buildbucket for the build status
[23:01:56.152] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:56.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:15:0) after 1m0s
[23:02:56.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:15:0)
[23:02:56.184] Timer tick, asking Buildbucket for the build status
[23:02:56.523] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:56.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:16:0) after 1m0s
[23:03:56.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:16:0)
[23:03:56.548] Timer tick, asking Buildbucket for the build status
[23:03:56.711] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:56.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:17:0) after 1m0s
[23:04:56.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:17:0)
[23:04:56.744] Timer tick, asking Buildbucket for the build status
[23:04:57.231] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:57.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:18:0) after 1m0s
[23:05:57.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:18:0)
[23:05:57.245] Timer tick, asking Buildbucket for the build status
[23:05:57.356] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:57.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:19:0) after 1m0s
[23:06:57.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:19:0)
[23:06:57.404] Timer tick, asking Buildbucket for the build status
[23:06:57.486] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:57.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:20:0) after 1m0s
[23:07:57.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:20:0)
[23:07:57.543] Timer tick, asking Buildbucket for the build status
[23:07:57.628] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:57.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:21:0) after 1m0s
[23:08:57.710] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:21:0)
[23:08:57.710] Timer tick, asking Buildbucket for the build status
[23:08:57.835] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:57.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:22:0) after 1m0s
[23:09:57.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:22:0)
[23:09:57.938] Timer tick, asking Buildbucket for the build status
[23:09:58.034] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:58.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:23:0) after 1m0s
[23:10:58.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:23:0)
[23:10:58.078] Timer tick, asking Buildbucket for the build status
[23:10:58.305] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:58.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:24:0) after 1m0s
[23:11:58.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:24:0)
[23:11:58.349] Timer tick, asking Buildbucket for the build status
[23:11:58.438] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:58.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:25:0) after 1m0s
[23:12:58.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:25:0)
[23:12:58.486] Timer tick, asking Buildbucket for the build status
[23:12:58.572] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:58.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:26:0) after 1m0s
[23:13:58.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:26:0)
[23:13:58.563] Timer tick, asking Buildbucket for the build status
[23:13:58.712] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:58.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:27:0) after 1m0s
[23:14:58.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:27:0)
[23:14:58.833] Timer tick, asking Buildbucket for the build status
[23:14:58.953] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:58.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:28:0) after 1m0s
[23:15:58.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:28:0)
[23:15:58.952] Timer tick, asking Buildbucket for the build status
[23:15:59.085] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:59.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:29:0) after 1m0s
[23:16:59.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:29:0)
[23:16:59.109] Timer tick, asking Buildbucket for the build status
[23:16:59.317] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:59.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:30:0) after 1m0s
[23:17:59.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:30:0)
[23:17:59.305] Timer tick, asking Buildbucket for the build status
[23:17:59.492] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:59.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:31:0) after 1m0s
[23:18:59.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:31:0)
[23:18:59.791] Timer tick, asking Buildbucket for the build status
[23:18:59.933] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:59.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:32:0) after 1m0s
[23:20:00.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:32:0)
[23:20:00.081] Timer tick, asking Buildbucket for the build status
[23:20:00.415] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:00.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:33:0) after 1m0s
[23:21:01.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:33:0)
[23:21:01.812] Timer tick, asking Buildbucket for the build status
[23:21:01.908] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:01.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:34:0) after 1m0s
[23:22:01.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:34:0)
[23:22:01.898] Timer tick, asking Buildbucket for the build status
[23:22:02.204] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:02.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:35:0) after 1m0s
[23:23:02.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:35:0)
[23:23:02.229] Timer tick, asking Buildbucket for the build status
[23:23:02.321] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:02.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:36:0) after 1m0s
[23:24:02.399] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:36:0)
[23:24:02.399] Timer tick, asking Buildbucket for the build status
[23:24:02.544] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:02.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:37:0) after 1m0s
[23:25:02.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:37:0)
[23:25:02.759] Timer tick, asking Buildbucket for the build status
[23:25:03.115] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:03.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:38:0) after 1m0s
[23:26:03.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:38:0)
[23:26:03.294] Timer tick, asking Buildbucket for the build status
[23:26:03.493] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:03.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:39:0) after 1m0s
[23:27:03.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:39:0)
[23:27:03.544] Timer tick, asking Buildbucket for the build status
[23:27:03.787] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:03.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:40:0) after 1m0s
[23:28:03.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:40:0)
[23:28:03.814] Timer tick, asking Buildbucket for the build status
[23:28:03.916] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:03.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:41:0) after 1m0s
[23:29:03.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:41:0)
[23:29:03.945] Timer tick, asking Buildbucket for the build status
[23:29:04.203] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:04.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:42:0) after 1m0s
[23:30:04.283] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:42:0)
[23:30:04.283] Timer tick, asking Buildbucket for the build status
[23:30:04.541] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:04.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:43:0) after 1m0s
[23:31:04.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:43:0)
[23:31:04.640] Timer tick, asking Buildbucket for the build status
[23:31:04.739] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:04.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:44:0) after 1m0s
[23:32:04.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:44:0)
[23:32:04.788] Timer tick, asking Buildbucket for the build status
[23:32:04.870] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:04.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:45:0) after 1m0s
[23:33:04.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:45:0)
[23:33:04.896] Timer tick, asking Buildbucket for the build status
[23:33:05.054] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:05.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:46:0) after 1m0s
[23:34:05.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:46:0)
[23:34:05.117] Timer tick, asking Buildbucket for the build status
[23:34:05.493] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:05.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:47:0) after 1m0s
[23:35:05.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:47:0)
[23:35:05.556] Timer tick, asking Buildbucket for the build status
[23:35:05.645] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:05.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:48:0) after 1m0s
[23:36:05.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:48:0)
[23:36:05.682] Timer tick, asking Buildbucket for the build status
[23:36:05.795] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:05.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:49:0) after 1m0s
[23:37:05.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:49:0)
[23:37:05.962] Timer tick, asking Buildbucket for the build status
[23:37:06.126] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:06.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:50:0) after 1m0s
[23:38:06.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:50:0)
[23:38:06.143] Timer tick, asking Buildbucket for the build status
[23:38:06.256] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:06.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:51:0) after 1m0s
[23:39:06.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:51:0)
[23:39:06.311] Timer tick, asking Buildbucket for the build status
[23:39:06.592] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:06.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:52:0) after 1m0s
[23:40:06.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:52:0)
[23:40:06.639] Timer tick, asking Buildbucket for the build status
[23:40:06.797] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:06.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:53:0) after 1m0s
[23:41:06.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:53:0)
[23:41:06.843] Timer tick, asking Buildbucket for the build status
[23:41:07.218] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:07.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:54:0) after 1m0s
[23:42:07.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:54:0)
[23:42:07.249] Timer tick, asking Buildbucket for the build status
[23:42:07.325] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:07.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:55:0) after 1m0s
[23:43:07.370] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:55:0)
[23:43:07.370] Timer tick, asking Buildbucket for the build status
[23:43:07.608] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:07.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:56:0) after 1m0s
[23:44:07.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:56:0)
[23:44:07.658] Timer tick, asking Buildbucket for the build status
[23:44:08.023] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:08.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:57:0) after 1m0s
[23:45:08.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:57:0)
[23:45:08.103] Timer tick, asking Buildbucket for the build status
[23:45:08.883] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:08.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:58:0) after 1m0s
[23:46:08.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:58:0)
[23:46:08.940] Timer tick, asking Buildbucket for the build status
[23:46:09.707] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:09.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:59:0) after 1m0s
[23:47:09.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:59:0)
[23:47:09.728] Timer tick, asking Buildbucket for the build status
[23:47:10.073] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:10.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:60:0) after 1m0s
[23:48:10.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:60:0)
[23:48:10.116] Timer tick, asking Buildbucket for the build status
[23:48:10.366] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:10.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:61:0) after 1m0s
[23:49:10.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:61:0)
[23:49:10.437] Timer tick, asking Buildbucket for the build status
[23:49:10.633] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:10.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:62:0) after 1m0s
[23:50:10.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:62:0)
[23:50:10.666] Timer tick, asking Buildbucket for the build status
[23:50:10.773] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:10.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:63:0) after 1m0s
[23:51:10.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:63:0)
[23:51:10.847] Timer tick, asking Buildbucket for the build status
[23:51:11.108] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:11.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:64:0) after 1m0s
[23:52:11.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:64:0)
[23:52:11.168] Timer tick, asking Buildbucket for the build status
[23:52:11.255] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:11.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:65:0) after 1m0s
[23:53:11.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:65:0)
[23:53:11.302] Timer tick, asking Buildbucket for the build status
[23:53:11.401] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:11.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:66:0) after 1m0s
[23:54:11.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:66:0)
[23:54:11.426] Timer tick, asking Buildbucket for the build status
[23:54:11.552] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:11.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:67:0) after 1m0s
[23:55:11.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:67:0)
[23:55:11.603] Timer tick, asking Buildbucket for the build status
[23:55:11.792] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:11.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:68:0) after 1m0s
[23:56:11.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:68:0)
[23:56:11.818] Timer tick, asking Buildbucket for the build status
[23:56:11.994] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:11.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:69:0) after 1m0s
[23:57:12.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:69:0)
[23:57:12.115] Timer tick, asking Buildbucket for the build status
[23:57:12.395] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:12.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:70:0) after 1m0s
[23:58:12.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:70:0)
[23:58:12.481] Timer tick, asking Buildbucket for the build status
[23:58:12.617] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:12.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:71:0) after 1m0s
[23:59:12.723] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:71:0)
[23:59:12.723] Timer tick, asking Buildbucket for the build status
[23:59:12.907] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:12.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:72:0) after 1m0s
[00:00:12.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:72:0)
[00:00:12.975] Timer tick, asking Buildbucket for the build status
[00:00:13.239] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:13.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:73:0) after 1m0s
[00:01:13.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:73:0)
[00:01:13.528] Timer tick, asking Buildbucket for the build status
[00:01:13.632] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:13.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:74:0) after 1m0s
[00:02:13.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:74:0)
[00:02:13.692] Timer tick, asking Buildbucket for the build status
[00:02:13.778] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:13.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:75:0) after 1m0s
[00:03:14.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:75:0)
[00:03:14.014] Timer tick, asking Buildbucket for the build status
[00:03:14.096] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:14.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:76:0) after 1m0s
[00:04:14.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:76:0)
[00:04:14.117] Timer tick, asking Buildbucket for the build status
[00:04:14.258] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:14.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:77:0) after 1m0s
[00:05:14.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:77:0)
[00:05:14.335] Timer tick, asking Buildbucket for the build status
[00:05:14.594] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:14.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:78:0) after 1m0s
[00:06:14.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:78:0)
[00:06:14.689] Timer tick, asking Buildbucket for the build status
[00:06:14.830] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:14.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:79:0) after 1m0s
[00:07:14.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:79:0)
[00:07:14.880] Timer tick, asking Buildbucket for the build status
[00:07:15.098] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:15.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:80:0) after 1m0s
[00:08:15.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:80:0)
[00:08:15.115] Timer tick, asking Buildbucket for the build status
[00:08:15.207] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:15.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:81:0) after 1m0s
[00:09:15.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:81:0)
[00:09:15.223] Timer tick, asking Buildbucket for the build status
[00:09:15.355] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:15.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:82:0) after 1m0s
[00:10:15.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:82:0)
[00:10:15.392] Timer tick, asking Buildbucket for the build status
[00:10:15.470] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:15.470] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:83:0) after 1m0s
[00:11:15.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:83:0)
[00:11:15.541] Timer tick, asking Buildbucket for the build status
[00:11:15.711] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:15.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:84:0) after 1m0s
[00:12:15.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:84:0)
[00:12:15.768] Timer tick, asking Buildbucket for the build status
[00:12:15.864] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:15.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:85:0) after 1m0s
[00:13:15.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:85:0)
[00:13:15.962] Timer tick, asking Buildbucket for the build status
[00:13:16.219] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:16.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:86:0) after 1m0s
[00:14:16.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:86:0)
[00:14:16.313] Timer tick, asking Buildbucket for the build status
[00:14:16.555] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:16.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:87:0) after 1m0s
[00:15:16.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:87:0)
[00:15:16.561] Timer tick, asking Buildbucket for the build status
[00:15:16.909] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:16.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:88:0) after 1m0s
[00:16:16.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:88:0)
[00:16:16.931] Timer tick, asking Buildbucket for the build status
[00:16:17.309] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:17.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:89:0) after 1m0s
[00:17:17.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:89:0)
[00:17:17.357] Timer tick, asking Buildbucket for the build status
[00:17:17.426] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:17.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:90:0) after 1m0s
[00:18:17.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:90:0)
[00:18:17.457] Timer tick, asking Buildbucket for the build status
[00:18:17.537] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:17.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:91:0) after 1m0s
[00:19:17.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:91:0)
[00:19:17.565] Timer tick, asking Buildbucket for the build status
[00:19:18.087] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:18.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:92:0) after 1m0s
[00:20:18.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:92:0)
[00:20:18.148] Timer tick, asking Buildbucket for the build status
[00:20:18.599] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:18.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:93:0) after 1m0s
[00:21:18.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:93:0)
[00:21:18.598] Timer tick, asking Buildbucket for the build status
[00:21:18.928] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:18.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:94:0) after 1m0s
[00:22:18.961] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:94:0)
[00:22:18.961] Timer tick, asking Buildbucket for the build status
[00:22:19.180] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:19.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:95:0) after 1m0s
[00:23:19.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:95:0)
[00:23:19.244] Timer tick, asking Buildbucket for the build status
[00:23:19.341] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:19.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:96:0) after 1m0s
[00:24:19.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:96:0)
[00:24:19.411] Timer tick, asking Buildbucket for the build status
[00:24:19.586] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:19.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:97:0) after 1m0s
[00:25:19.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:97:0)
[00:25:19.603] Timer tick, asking Buildbucket for the build status
[00:25:19.850] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:19.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:98:0) after 1m0s
[00:26:19.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:98:0)
[00:26:19.898] Timer tick, asking Buildbucket for the build status
[00:26:20.010] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:20.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:99:0) after 1m0s
[00:27:20.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:99:0)
[00:27:20.075] Timer tick, asking Buildbucket for the build status
[00:27:20.164] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:20.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:100:0) after 1m0s
[00:28:20.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:100:0)
[00:28:20.155] Timer tick, asking Buildbucket for the build status
[00:28:20.263] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:20.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:101:0) after 1m0s
[00:29:20.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:101:0)
[00:29:20.278] Timer tick, asking Buildbucket for the build status
[00:29:20.487] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:20.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:102:0) after 1m0s
[00:30:20.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:102:0)
[00:30:20.522] Timer tick, asking Buildbucket for the build status
[00:30:20.728] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:20.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:103:0) after 1m0s
[00:31:20.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:103:0)
[00:31:20.768] Timer tick, asking Buildbucket for the build status
[00:31:20.944] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:20.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:104:0) after 1m0s
[00:32:21.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:104:0)
[00:32:21.005] Timer tick, asking Buildbucket for the build status
[00:32:21.110] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:21.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:105:0) after 1m0s
[00:33:21.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:105:0)
[00:33:21.252] Timer tick, asking Buildbucket for the build status
[00:33:21.490] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:21.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:106:0) after 1m0s
[00:34:21.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:106:0)
[00:34:21.525] Timer tick, asking Buildbucket for the build status
[00:34:21.775] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:21.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:107:0) after 1m0s
[00:35:21.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:107:0)
[00:35:21.803] Timer tick, asking Buildbucket for the build status
[00:35:21.906] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:21.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:108:0) after 1m0s
[00:36:21.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:108:0)
[00:36:21.908] Timer tick, asking Buildbucket for the build status
[00:36:22.004] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:22.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:109:0) after 1m0s
[00:37:22.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:109:0)
[00:37:22.031] Timer tick, asking Buildbucket for the build status
[00:37:22.536] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:22.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:110:0) after 1m0s
[00:38:22.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:110:0)
[00:38:22.636] Timer tick, asking Buildbucket for the build status
[00:38:22.948] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:22.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:111:0) after 1m0s
[00:39:22.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:111:0)
[00:39:22.995] Timer tick, asking Buildbucket for the build status
[00:39:23.105] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:23.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:112:0) after 1m0s
[00:40:23.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:112:0)
[00:40:23.216] Timer tick, asking Buildbucket for the build status
[00:40:23.288] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:23.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:113:0) after 1m0s
[00:41:23.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:113:0)
[00:41:23.324] Timer tick, asking Buildbucket for the build status
[00:41:23.407] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:23.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:114:0) after 1m0s
[00:42:23.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:114:0)
[00:42:23.446] Timer tick, asking Buildbucket for the build status
[00:42:23.638] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:23.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:115:0) after 1m0s
[00:43:23.662] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:115:0)
[00:43:23.662] Timer tick, asking Buildbucket for the build status
[00:43:23.826] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:23.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:116:0) after 1m0s
[00:44:23.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:116:0)
[00:44:23.835] Timer tick, asking Buildbucket for the build status
[00:44:23.948] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:23.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:117:0) after 1m0s
[00:45:23.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:117:0)
[00:45:23.939] Timer tick, asking Buildbucket for the build status
[00:45:24.169] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:24.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:118:0) after 1m0s
[00:46:24.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:118:0)
[00:46:24.245] Timer tick, asking Buildbucket for the build status
[00:46:24.333] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:24.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:119:0) after 1m0s
[00:47:24.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:119:0)
[00:47:24.385] Timer tick, asking Buildbucket for the build status
[00:47:24.796] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:24.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:120:0) after 1m0s
[00:48:25.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:120:0)
[00:48:25.106] Timer tick, asking Buildbucket for the build status
[00:48:25.205] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:25.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:121:0) after 1m0s
[00:49:25.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:121:0)
[00:49:25.435] Timer tick, asking Buildbucket for the build status
[00:49:25.836] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:25.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:122:0) after 1m0s
[00:50:26.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:122:0)
[00:50:26.019] Timer tick, asking Buildbucket for the build status
[00:50:26.184] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:26.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:123:0) after 1m0s
[00:51:26.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:123:0)
[00:51:26.425] Timer tick, asking Buildbucket for the build status
[00:51:26.729] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:26.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:124:0) after 1m0s
[00:52:26.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:124:0)
[00:52:26.978] Timer tick, asking Buildbucket for the build status
[00:52:28.114] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:28.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:125:0) after 1m0s
[00:53:28.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:125:0)
[00:53:28.487] Timer tick, asking Buildbucket for the build status
[00:53:28.567] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:28.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:126:0) after 1m0s
[00:54:28.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:126:0)
[00:54:28.695] Timer tick, asking Buildbucket for the build status
[00:54:28.808] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:28.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:127:0) after 1m0s
[00:55:29.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:127:0)
[00:55:29.046] Timer tick, asking Buildbucket for the build status
[00:55:29.333] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:29.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:128:0) after 1m0s
[00:56:29.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:128:0)
[00:56:29.556] Timer tick, asking Buildbucket for the build status
[00:56:29.725] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:29.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:129:0) after 1m0s
[00:57:29.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:129:0)
[00:57:29.879] Timer tick, asking Buildbucket for the build status
[00:57:30.074] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:30.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:130:0) after 1m0s
[00:58:30.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:130:0)
[00:58:30.195] Timer tick, asking Buildbucket for the build status
[00:58:30.412] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:30.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:131:0) after 1m0s
[00:59:30.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:131:0)
[00:59:30.574] Timer tick, asking Buildbucket for the build status
[00:59:30.723] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:30.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:132:0) after 1m0s
[01:00:30.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:132:0)
[01:00:30.872] Timer tick, asking Buildbucket for the build status
[01:00:31.000] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:31.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:133:0) after 1m0s
[01:01:31.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:133:0)
[01:01:31.077] Timer tick, asking Buildbucket for the build status
[01:01:31.322] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:31.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:134:0) after 1m0s
[01:02:31.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:134:0)
[01:02:31.411] Timer tick, asking Buildbucket for the build status
[01:02:31.501] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:31.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:135:0) after 1m0s
[01:03:31.693] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:135:0)
[01:03:31.693] Timer tick, asking Buildbucket for the build status
[01:03:32.206] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:32.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:136:0) after 1m0s
[01:04:32.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:136:0)
[01:04:32.317] Timer tick, asking Buildbucket for the build status
[01:04:32.793] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:32.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:137:0) after 1m0s
[01:05:32.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:137:0)
[01:05:32.877] Timer tick, asking Buildbucket for the build status
[01:05:33.243] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:33.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:138:0) after 1m0s
[01:06:33.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:138:0)
[01:06:33.353] Timer tick, asking Buildbucket for the build status
[01:06:33.436] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:33.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:139:0) after 1m0s
[01:07:33.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:139:0)
[01:07:33.540] Timer tick, asking Buildbucket for the build status
[01:07:33.633] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:33.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:140:0) after 1m0s
[01:08:33.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:140:0)
[01:08:33.671] Timer tick, asking Buildbucket for the build status
[01:08:33.769] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:33.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:141:0) after 1m0s
[01:09:33.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:141:0)
[01:09:33.825] Timer tick, asking Buildbucket for the build status
[01:09:33.928] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:33.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:142:0) after 1m0s
[01:10:33.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:142:0)
[01:10:33.951] Timer tick, asking Buildbucket for the build status
[01:10:34.049] Build 8869595364564209424: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:34.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:143:0) after 1m0s
[01:10:36.773] Received PubSub notification, asking Buildbucket for the build status
[01:10:37.093] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:34.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:143:0)
[01:11:34.136] Timer tick, asking Buildbucket for the build status
[01:11:34.310] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:34.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:145:0) after 1m0s
[01:12:34.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:145:0)
[01:12:34.482] Timer tick, asking Buildbucket for the build status
[01:12:34.698] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:34.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:146:0) after 1m0s
[01:13:34.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:146:0)
[01:13:34.727] Timer tick, asking Buildbucket for the build status
[01:13:35.103] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:35.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:147:0) after 1m0s
[01:14:35.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:147:0)
[01:14:35.111] Timer tick, asking Buildbucket for the build status
[01:14:35.569] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:35.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:148:0) after 1m0s
[01:15:35.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:148:0)
[01:15:35.633] Timer tick, asking Buildbucket for the build status
[01:15:35.783] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:35.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:149:0) after 1m0s
[01:16:35.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:149:0)
[01:16:35.977] Timer tick, asking Buildbucket for the build status
[01:16:36.102] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:36.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:150:0) after 1m0s
[01:17:36.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:150:0)
[01:17:36.303] Timer tick, asking Buildbucket for the build status
[01:17:36.417] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:36.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:151:0) after 1m0s
[01:18:36.558] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:151:0)
[01:18:36.558] Timer tick, asking Buildbucket for the build status
[01:18:36.638] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:36.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:152:0) after 1m0s
[01:19:36.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:152:0)
[01:19:36.778] Timer tick, asking Buildbucket for the build status
[01:19:37.113] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:37.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:153:0) after 1m0s
[01:20:37.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:153:0)
[01:20:37.223] Timer tick, asking Buildbucket for the build status
[01:20:37.591] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:37.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:154:0) after 1m0s
[01:21:37.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:154:0)
[01:21:37.685] Timer tick, asking Buildbucket for the build status
[01:21:37.919] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:37.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:155:0) after 1m0s
[01:22:37.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:155:0)
[01:22:37.959] Timer tick, asking Buildbucket for the build status
[01:22:38.181] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:38.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:156:0) after 1m0s
[01:23:38.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:156:0)
[01:23:38.259] Timer tick, asking Buildbucket for the build status
[01:23:38.334] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:38.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:157:0) after 1m0s
[01:24:38.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:157:0)
[01:24:38.492] Timer tick, asking Buildbucket for the build status
[01:24:38.599] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:38.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:158:0) after 1m0s
[01:25:38.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:158:0)
[01:25:38.649] Timer tick, asking Buildbucket for the build status
[01:25:38.773] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:38.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:159:0) after 1m0s
[01:26:38.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:159:0)
[01:26:38.902] Timer tick, asking Buildbucket for the build status
[01:26:39.508] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:39.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:160:0) after 1m0s
[01:27:39.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:160:0)
[01:27:39.665] Timer tick, asking Buildbucket for the build status
[01:27:40.078] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:40.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:161:0) after 1m0s
[01:28:40.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:161:0)
[01:28:40.124] Timer tick, asking Buildbucket for the build status
[01:28:40.248] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:40.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:162:0) after 1m0s
[01:29:40.407] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:162:0)
[01:29:40.407] Timer tick, asking Buildbucket for the build status
[01:29:40.546] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:40.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:163:0) after 1m0s
[01:30:40.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:163:0)
[01:30:40.674] Timer tick, asking Buildbucket for the build status
[01:30:40.874] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:40.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:164:0) after 1m0s
[01:31:41.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:164:0)
[01:31:41.347] Timer tick, asking Buildbucket for the build status
[01:31:41.686] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:41.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:165:0) after 1m0s
[01:32:41.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:165:0)
[01:32:41.855] Timer tick, asking Buildbucket for the build status
[01:32:42.057] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:42.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:166:0) after 1m0s
[01:33:42.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:166:0)
[01:33:42.142] Timer tick, asking Buildbucket for the build status
[01:33:42.489] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:42.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:167:0) after 1m0s
[01:34:42.611] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:167:0)
[01:34:42.611] Timer tick, asking Buildbucket for the build status
[01:34:42.784] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:42.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:168:0) after 1m0s
[01:35:42.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:168:0)
[01:35:42.839] Timer tick, asking Buildbucket for the build status
[01:35:43.016] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:43.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:169:0) after 1m0s
[01:36:43.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:169:0)
[01:36:43.061] Timer tick, asking Buildbucket for the build status
[01:36:43.407] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:43.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:170:0) after 1m0s
[01:37:43.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:170:0)
[01:37:43.476] Timer tick, asking Buildbucket for the build status
[01:37:43.595] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:43.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:171:0) after 1m0s
[01:38:43.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:171:0)
[01:38:43.634] Timer tick, asking Buildbucket for the build status
[01:38:43.758] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:43.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:172:0) after 1m0s
[01:39:43.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:172:0)
[01:39:43.848] Timer tick, asking Buildbucket for the build status
[01:39:44.092] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:44.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:173:0) after 1m0s
[01:40:44.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:173:0)
[01:40:44.153] Timer tick, asking Buildbucket for the build status
[01:40:44.278] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:44.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:174:0) after 1m0s
[01:41:44.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:174:0)
[01:41:44.346] Timer tick, asking Buildbucket for the build status
[01:41:44.435] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:44.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:175:0) after 1m0s
[01:42:44.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:175:0)
[01:42:44.479] Timer tick, asking Buildbucket for the build status
[01:42:44.568] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:44.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:176:0) after 1m0s
[01:43:44.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:176:0)
[01:43:44.788] Timer tick, asking Buildbucket for the build status
[01:43:44.890] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:44.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:177:0) after 1m0s
[01:44:44.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:177:0)
[01:44:44.942] Timer tick, asking Buildbucket for the build status
[01:44:45.073] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:45.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:178:0) after 1m0s
[01:45:45.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:178:0)
[01:45:45.108] Timer tick, asking Buildbucket for the build status
[01:45:45.269] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:45.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:179:0) after 1m0s
[01:46:45.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:179:0)
[01:46:45.311] Timer tick, asking Buildbucket for the build status
[01:46:45.409] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:45.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:180:0) after 1m0s
[01:47:45.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:180:0)
[01:47:45.495] Timer tick, asking Buildbucket for the build status
[01:47:45.627] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:45.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:181:0) after 1m0s
[01:48:45.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:181:0)
[01:48:45.654] Timer tick, asking Buildbucket for the build status
[01:48:45.747] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:45.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:182:0) after 1m0s
[01:49:45.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:182:0)
[01:49:45.802] Timer tick, asking Buildbucket for the build status
[01:49:46.201] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:46.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:183:0) after 1m0s
[01:50:46.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:183:0)
[01:50:46.853] Timer tick, asking Buildbucket for the build status
[01:50:46.947] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:46.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:184:0) after 1m0s
[01:51:47.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:184:0)
[01:51:47.074] Timer tick, asking Buildbucket for the build status
[01:51:47.280] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:47.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:185:0) after 1m0s
[01:52:47.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:185:0)
[01:52:47.337] Timer tick, asking Buildbucket for the build status
[01:52:47.414] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:47.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:186:0) after 1m0s
[01:53:47.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:186:0)
[01:53:47.426] Timer tick, asking Buildbucket for the build status
[01:53:47.680] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:47.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:187:0) after 1m0s
[01:54:47.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:187:0)
[01:54:47.971] Timer tick, asking Buildbucket for the build status
[01:54:48.109] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:48.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:188:0) after 1m0s
[01:55:48.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:188:0)
[01:55:48.178] Timer tick, asking Buildbucket for the build status
[01:55:48.263] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:48.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:189:0) after 1m0s
[01:56:48.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:189:0)
[01:56:48.373] Timer tick, asking Buildbucket for the build status
[01:56:48.615] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:48.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:190:0) after 1m0s
[01:57:48.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:190:0)
[01:57:48.817] Timer tick, asking Buildbucket for the build status
[01:57:48.897] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:48.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:191:0) after 1m0s
[01:58:48.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:191:0)
[01:58:48.978] Timer tick, asking Buildbucket for the build status
[01:58:49.179] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:49.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:192:0) after 1m0s
[01:59:49.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:192:0)
[01:59:49.220] Timer tick, asking Buildbucket for the build status
[01:59:49.349] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:49.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:193:0) after 1m0s
[02:00:49.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:193:0)
[02:00:49.352] Timer tick, asking Buildbucket for the build status
[02:00:49.465] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:49.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:194:0) after 1m0s
[02:01:49.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:194:0)
[02:01:49.551] Timer tick, asking Buildbucket for the build status
[02:01:49.641] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:49.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:195:0) after 1m0s
[02:02:49.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:195:0)
[02:02:49.679] Timer tick, asking Buildbucket for the build status
[02:02:49.828] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:49.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:196:0) after 1m0s
[02:03:49.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:196:0)
[02:03:49.809] Timer tick, asking Buildbucket for the build status
[02:03:49.867] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:49.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:197:0) after 1m0s
[02:04:49.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:197:0)
[02:04:49.949] Timer tick, asking Buildbucket for the build status
[02:04:50.107] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:50.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:198:0) after 1m0s
[02:05:50.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:198:0)
[02:05:50.196] Timer tick, asking Buildbucket for the build status
[02:05:50.451] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:50.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:199:0) after 1m0s
[02:06:50.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:199:0)
[02:06:50.575] Timer tick, asking Buildbucket for the build status
[02:06:50.916] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:50.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:200:0) after 1m0s
[02:07:51.040] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:200:0)
[02:07:51.040] Timer tick, asking Buildbucket for the build status
[02:07:51.120] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:51.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:201:0) after 1m0s
[02:08:51.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:201:0)
[02:08:51.226] Timer tick, asking Buildbucket for the build status
[02:08:51.450] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:51.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:202:0) after 1m0s
[02:09:51.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:202:0)
[02:09:51.651] Timer tick, asking Buildbucket for the build status
[02:09:52.026] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:52.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:203:0) after 1m0s
[02:10:52.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:203:0)
[02:10:52.367] Timer tick, asking Buildbucket for the build status
[02:10:52.641] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:52.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:204:0) after 1m0s
[02:11:52.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:204:0)
[02:11:52.786] Timer tick, asking Buildbucket for the build status
[02:11:53.004] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:53.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:205:0) after 1m0s
[02:12:53.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:205:0)
[02:12:53.114] Timer tick, asking Buildbucket for the build status
[02:12:53.215] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:53.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:206:0) after 1m0s
[02:13:53.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:206:0)
[02:13:53.298] Timer tick, asking Buildbucket for the build status
[02:13:53.365] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:53.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:207:0) after 1m0s
[02:14:53.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:207:0)
[02:14:53.409] Timer tick, asking Buildbucket for the build status
[02:14:53.567] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:53.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:208:0) after 1m0s
[02:15:53.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:208:0)
[02:15:53.744] Timer tick, asking Buildbucket for the build status
[02:15:53.821] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:53.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:209:0) after 1m0s
[02:16:53.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:209:0)
[02:16:53.960] Timer tick, asking Buildbucket for the build status
[02:16:54.124] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:54.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:210:0) after 1m0s
[02:17:54.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:210:0)
[02:17:54.174] Timer tick, asking Buildbucket for the build status
[02:17:54.265] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:54.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:211:0) after 1m0s
[02:18:54.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:211:0)
[02:18:54.312] Timer tick, asking Buildbucket for the build status
[02:18:54.377] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:54.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:212:0) after 1m0s
[02:19:54.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:212:0)
[02:19:54.590] Timer tick, asking Buildbucket for the build status
[02:19:54.689] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:54.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:213:0) after 1m0s
[02:20:54.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:213:0)
[02:20:54.847] Timer tick, asking Buildbucket for the build status
[02:20:55.124] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:55.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:214:0) after 1m0s
[02:21:55.755] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:214:0)
[02:21:55.755] Timer tick, asking Buildbucket for the build status
[02:21:55.844] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:55.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:215:0) after 1m0s
[02:22:56.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:215:0)
[02:22:56.275] Timer tick, asking Buildbucket for the build status
[02:22:56.471] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:56.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:216:0) after 1m0s
[02:23:56.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:216:0)
[02:23:56.720] Timer tick, asking Buildbucket for the build status
[02:23:57.057] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:57.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:217:0) after 1m0s
[02:24:57.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:217:0)
[02:24:57.086] Timer tick, asking Buildbucket for the build status
[02:24:57.278] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:57.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:218:0) after 1m0s
[02:25:57.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:218:0)
[02:25:57.306] Timer tick, asking Buildbucket for the build status
[02:25:57.636] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:57.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:219:0) after 1m0s
[02:26:57.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:219:0)
[02:26:57.841] Timer tick, asking Buildbucket for the build status
[02:26:58.127] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:58.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:220:0) after 1m0s
[02:27:58.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:220:0)
[02:27:58.253] Timer tick, asking Buildbucket for the build status
[02:27:58.712] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:58.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:221:0) after 1m0s
[02:28:58.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:221:0)
[02:28:58.895] Timer tick, asking Buildbucket for the build status
[02:28:59.055] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:59.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:222:0) after 1m0s
[02:29:59.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:222:0)
[02:29:59.078] Timer tick, asking Buildbucket for the build status
[02:29:59.296] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:59.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:223:0) after 1m0s
[02:30:59.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:223:0)
[02:30:59.374] Timer tick, asking Buildbucket for the build status
[02:31:00.155] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:00.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:224:0) after 1m0s
[02:32:00.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:224:0)
[02:32:00.169] Timer tick, asking Buildbucket for the build status
[02:32:00.485] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:00.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:225:0) after 1m0s
[02:33:01.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:225:0)
[02:33:01.768] Timer tick, asking Buildbucket for the build status
[02:33:02.032] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:02.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:226:0) after 1m0s
[02:34:02.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:226:0)
[02:34:02.229] Timer tick, asking Buildbucket for the build status
[02:34:02.631] Build 8869595364564209424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:02.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9035025426664820128:227:0) after 1m0s
[02:34:45.746] Received PubSub notification, asking Buildbucket for the build status
[02:34:45.930] Build 8869595364564209424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:34:45.979] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1599705284058648",
    "created_by": "project:nacl",
    "created_ts": "1599691726107442",
    "failure_reason": "BUILD_FAILURE",
    "id": "8869595364564209424",
    "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\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"title\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/69a0d6e8affc94187af10e0673592d1b238c6eb0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}}",
    "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\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"title\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/69a0d6e8affc94187af10e0673592d1b238c6eb0\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-6ltk\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5168, \"got_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-6ltk\"], \"image\": [\"chrome-xenial-20090600-f98a2a06cf8\"], \"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\": [\"5513-ee65082\"], \"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": "1599700235778107",
    "status": "COMPLETED",
    "status_changed_ts": "1599700236249675",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5168",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/69a0d6e8affc94187af10e0673592d1b238c6eb0",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/69a0d6e8affc94187af10e0673592d1b238c6eb0",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9035025426664820128",
      "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/8869595364564209424/+/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:4e8de038b5e24510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1599705284759705",
    "url": "https://ci.chromium.org/b/8869595364564209424",
    "utcnow_ts": "1599705285821671"
  }
}
[02:34:45.985] Invocation finished in 3h46m1.264889386s with status FAILED