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

• c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0 (5 years ago)

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

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

[23:12:43.388] New invocation is queued and will start shortly
[23:12:51.272] Starting the invocation (attempt 1)
[23:12:51.309] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:12:51.309] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:12:51.309] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9051965550843923600",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"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\":\"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\",\"title\":\"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_64",
    "scheduler_invocation_id:9051965550843923600",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
    "gitiles_ref:refs/heads/master"
  ]
}
[23:12:52.403] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1583536371517140",
    "id": "8886535481657987760",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"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\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"title\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1583536372254638",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5090",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9051965550843923600",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8886535481657987760/+/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": "1583536372254741",
    "url": "https://ci.chromium.org/b/8886535481657987760",
    "utcnow_ts": "1583536372392169"
  }
}
[23:12:52.403] Task URL: https://ci.chromium.org/b/8886535481657987760
[23:12:52.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:2:0) after 1m0s
[23:13:52.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:2:0)
[23:13:52.378] Timer tick, asking Buildbucket for the build status
[23:13:52.507] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:52.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:3:0) after 1m0s
[23:14:52.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:3:0)
[23:14:52.527] Timer tick, asking Buildbucket for the build status
[23:14:52.647] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:52.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:4:0) after 1m0s
[23:15:52.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:4:0)
[23:15:52.930] Timer tick, asking Buildbucket for the build status
[23:15:53.304] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:53.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:5:0) after 1m0s
[23:16:53.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:5:0)
[23:16:53.501] Timer tick, asking Buildbucket for the build status
[23:16:53.637] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:53.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:6:0) after 1m0s
[23:17:53.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:6:0)
[23:17:53.777] Timer tick, asking Buildbucket for the build status
[23:17:53.924] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:53.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:7:0) after 1m0s
[23:18:53.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:7:0)
[23:18:53.995] Timer tick, asking Buildbucket for the build status
[23:18:54.371] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:54.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:8:0) after 1m0s
[23:19:54.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:8:0)
[23:19:54.467] Timer tick, asking Buildbucket for the build status
[23:19:54.569] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:54.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:9:0) after 1m0s
[23:20:54.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:9:0)
[23:20:54.673] Timer tick, asking Buildbucket for the build status
[23:20:55.052] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:55.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:10:0) after 1m0s
[23:21:55.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:10:0)
[23:21:55.214] Timer tick, asking Buildbucket for the build status
[23:21:55.529] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:55.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:11:0) after 1m0s
[23:22:55.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:11:0)
[23:22:55.678] Timer tick, asking Buildbucket for the build status
[23:22:55.800] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:55.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:12:0) after 1m0s
[23:23:55.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:12:0)
[23:23:55.884] Timer tick, asking Buildbucket for the build status
[23:23:56.024] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:56.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:13:0) after 1m0s
[23:24:56.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:13:0)
[23:24:56.075] Timer tick, asking Buildbucket for the build status
[23:24:56.610] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:56.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:14:0) after 1m0s
[23:25:56.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:14:0)
[23:25:56.703] Timer tick, asking Buildbucket for the build status
[23:25:56.947] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:56.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:15:0) after 1m0s
[23:26:57.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:15:0)
[23:26:57.052] Timer tick, asking Buildbucket for the build status
[23:26:57.311] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:57.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:16:0) after 1m0s
[23:27:57.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:16:0)
[23:27:57.387] Timer tick, asking Buildbucket for the build status
[23:27:57.526] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:57.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:17:0) after 1m0s
[23:28:57.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:17:0)
[23:28:57.610] Timer tick, asking Buildbucket for the build status
[23:28:57.743] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:57.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:18:0) after 1m0s
[23:29:57.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:18:0)
[23:29:57.807] Timer tick, asking Buildbucket for the build status
[23:29:58.227] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:58.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:19:0) after 1m0s
[23:30:58.257] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:19:0)
[23:30:58.257] Timer tick, asking Buildbucket for the build status
[23:30:58.436] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:58.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:20:0) after 1m0s
[23:31:58.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:20:0)
[23:31:58.454] Timer tick, asking Buildbucket for the build status
[23:31:58.964] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:58.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:21:0) after 1m0s
[23:32:59.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:21:0)
[23:32:59.027] Timer tick, asking Buildbucket for the build status
[23:32:59.185] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:59.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:22:0) after 1m0s
[23:33:59.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:22:0)
[23:33:59.206] Timer tick, asking Buildbucket for the build status
[23:33:59.465] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:59.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:23:0) after 1m0s
[23:34:59.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:23:0)
[23:34:59.569] Timer tick, asking Buildbucket for the build status
[23:34:59.702] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:59.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:24:0) after 1m0s
[23:35:59.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:24:0)
[23:35:59.791] Timer tick, asking Buildbucket for the build status
[23:35:59.945] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:59.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:25:0) after 1m0s
[23:36:59.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:25:0)
[23:36:59.984] Timer tick, asking Buildbucket for the build status
[23:37:00.312] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:00.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:26:0) after 1m0s
[23:38:00.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:26:0)
[23:38:00.341] Timer tick, asking Buildbucket for the build status
[23:38:00.550] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:00.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:27:0) after 1m0s
[23:39:00.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:27:0)
[23:39:00.593] Timer tick, asking Buildbucket for the build status
[23:39:00.695] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:00.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:28:0) after 1m0s
[23:40:00.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:28:0)
[23:40:00.711] Timer tick, asking Buildbucket for the build status
[23:40:00.829] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:00.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:29:0) after 1m0s
[23:41:00.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:29:0)
[23:41:00.997] Timer tick, asking Buildbucket for the build status
[23:41:01.148] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:01.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:30:0) after 1m0s
[23:42:01.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:30:0)
[23:42:01.129] Timer tick, asking Buildbucket for the build status
[23:42:01.280] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:01.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:31:0) after 1m0s
[23:43:01.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:31:0)
[23:43:01.333] Timer tick, asking Buildbucket for the build status
[23:43:01.453] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:01.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:32:0) after 1m0s
[23:44:01.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:32:0)
[23:44:01.506] Timer tick, asking Buildbucket for the build status
[23:44:01.665] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:01.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:33:0) after 1m0s
[23:45:01.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:33:0)
[23:45:01.686] Timer tick, asking Buildbucket for the build status
[23:45:01.972] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:01.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:34:0) after 1m0s
[23:46:02.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:34:0)
[23:46:02.001] Timer tick, asking Buildbucket for the build status
[23:46:02.221] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:02.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:35:0) after 1m0s
[23:47:02.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:35:0)
[23:47:02.278] Timer tick, asking Buildbucket for the build status
[23:47:02.600] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:02.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:36:0) after 1m0s
[23:48:02.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:36:0)
[23:48:02.658] Timer tick, asking Buildbucket for the build status
[23:48:02.819] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:02.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:37:0) after 1m0s
[23:49:02.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:37:0)
[23:49:02.895] Timer tick, asking Buildbucket for the build status
[23:49:03.064] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:03.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:38:0) after 1m0s
[23:50:03.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:38:0)
[23:50:03.120] Timer tick, asking Buildbucket for the build status
[23:50:03.218] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:03.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:39:0) after 1m0s
[23:51:03.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:39:0)
[23:51:03.275] Timer tick, asking Buildbucket for the build status
[23:51:03.494] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:03.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:40:0) after 1m0s
[23:52:03.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:40:0)
[23:52:03.534] Timer tick, asking Buildbucket for the build status
[23:52:03.701] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:03.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:41:0) after 1m0s
[23:53:03.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:41:0)
[23:53:03.728] Timer tick, asking Buildbucket for the build status
[23:53:03.842] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:03.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:42:0) after 1m0s
[23:54:04.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:42:0)
[23:54:04.206] Timer tick, asking Buildbucket for the build status
[23:54:04.411] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:04.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:43:0) after 1m0s
[23:55:04.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:43:0)
[23:55:04.768] Timer tick, asking Buildbucket for the build status
[23:55:04.953] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:04.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:44:0) after 1m0s
[23:56:05.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:44:0)
[23:56:05.165] Timer tick, asking Buildbucket for the build status
[23:56:05.332] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:05.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:45:0) after 1m0s
[23:57:05.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:45:0)
[23:57:05.425] Timer tick, asking Buildbucket for the build status
[23:57:05.506] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:05.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:46:0) after 1m0s
[23:58:05.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:46:0)
[23:58:05.562] Timer tick, asking Buildbucket for the build status
[23:58:05.699] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:05.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:47:0) after 1m0s
[23:59:05.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:47:0)
[23:59:05.745] Timer tick, asking Buildbucket for the build status
[23:59:05.921] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:05.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:48:0) after 1m0s
[00:00:05.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:48:0)
[00:00:05.943] Timer tick, asking Buildbucket for the build status
[00:00:06.046] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:06.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:49:0) after 1m0s
[00:01:06.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:49:0)
[00:01:06.232] Timer tick, asking Buildbucket for the build status
[00:01:06.320] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:06.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:50:0) after 1m0s
[00:02:06.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:50:0)
[00:02:06.406] Timer tick, asking Buildbucket for the build status
[00:02:06.617] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:06.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:51:0) after 1m0s
[00:03:06.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:51:0)
[00:03:06.768] Timer tick, asking Buildbucket for the build status
[00:03:06.980] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:06.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:52:0) after 1m0s
[00:04:07.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:52:0)
[00:04:07.089] Timer tick, asking Buildbucket for the build status
[00:04:07.220] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:07.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:53:0) after 1m0s
[00:05:07.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:53:0)
[00:05:07.240] Timer tick, asking Buildbucket for the build status
[00:05:07.457] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:07.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:54:0) after 1m0s
[00:06:07.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:54:0)
[00:06:07.535] Timer tick, asking Buildbucket for the build status
[00:06:07.711] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:07.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:55:0) after 1m0s
[00:07:07.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:55:0)
[00:07:07.803] Timer tick, asking Buildbucket for the build status
[00:07:08.174] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:08.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:56:0) after 1m0s
[00:08:08.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:56:0)
[00:08:08.269] Timer tick, asking Buildbucket for the build status
[00:08:08.372] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:08.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:57:0) after 1m0s
[00:09:08.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:57:0)
[00:09:08.551] Timer tick, asking Buildbucket for the build status
[00:09:09.063] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:09.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:58:0) after 1m0s
[00:10:09.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:58:0)
[00:10:09.116] Timer tick, asking Buildbucket for the build status
[00:10:09.538] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:09.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:59:0) after 1m0s
[00:11:09.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:59:0)
[00:11:09.695] Timer tick, asking Buildbucket for the build status
[00:11:10.376] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:10.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:60:0) after 1m0s
[00:12:10.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:60:0)
[00:12:10.419] Timer tick, asking Buildbucket for the build status
[00:12:10.530] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:10.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:61:0) after 1m0s
[00:13:10.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:61:0)
[00:13:10.561] Timer tick, asking Buildbucket for the build status
[00:13:10.670] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:10.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:62:0) after 1m0s
[00:14:10.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:62:0)
[00:14:10.785] Timer tick, asking Buildbucket for the build status
[00:14:10.910] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:10.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:63:0) after 1m0s
[00:15:11.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:63:0)
[00:15:11.001] Timer tick, asking Buildbucket for the build status
[00:15:11.168] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:11.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:64:0) after 1m0s
[00:16:11.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:64:0)
[00:16:11.201] Timer tick, asking Buildbucket for the build status
[00:16:11.447] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:11.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:65:0) after 1m0s
[00:17:11.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:65:0)
[00:17:11.997] Timer tick, asking Buildbucket for the build status
[00:17:12.271] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:12.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:66:0) after 1m0s
[00:18:12.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:66:0)
[00:18:12.415] Timer tick, asking Buildbucket for the build status
[00:18:12.756] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:12.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:67:0) after 1m0s
[00:19:12.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:67:0)
[00:19:12.880] Timer tick, asking Buildbucket for the build status
[00:19:13.007] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:13.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:68:0) after 1m0s
[00:20:13.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:68:0)
[00:20:13.260] Timer tick, asking Buildbucket for the build status
[00:20:13.417] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:13.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:69:0) after 1m0s
[00:21:13.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:69:0)
[00:21:13.482] Timer tick, asking Buildbucket for the build status
[00:21:14.245] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:14.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:70:0) after 1m0s
[00:22:14.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:70:0)
[00:22:14.333] Timer tick, asking Buildbucket for the build status
[00:22:14.551] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:14.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:71:0) after 1m0s
[00:23:14.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:71:0)
[00:23:14.607] Timer tick, asking Buildbucket for the build status
[00:23:14.867] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:14.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:72:0) after 1m0s
[00:24:14.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:72:0)
[00:24:14.894] Timer tick, asking Buildbucket for the build status
[00:24:15.392] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:15.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:73:0) after 1m0s
[00:25:15.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:73:0)
[00:25:15.425] Timer tick, asking Buildbucket for the build status
[00:25:16.558] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:16.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:74:0) after 1m0s
[00:26:16.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:74:0)
[00:26:16.552] Timer tick, asking Buildbucket for the build status
[00:26:19.089] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:19.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:75:0) after 1m0s
[00:27:19.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:75:0)
[00:27:19.164] Timer tick, asking Buildbucket for the build status
[00:27:19.368] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:19.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:76:0) after 1m0s
[00:28:19.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:76:0)
[00:28:19.529] Timer tick, asking Buildbucket for the build status
[00:28:19.631] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:19.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:77:0) after 1m0s
[00:29:19.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:77:0)
[00:29:19.640] Timer tick, asking Buildbucket for the build status
[00:29:19.833] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:19.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:78:0) after 1m0s
[00:30:19.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:78:0)
[00:30:19.944] Timer tick, asking Buildbucket for the build status
[00:30:20.096] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:20.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:79:0) after 1m0s
[00:31:20.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:79:0)
[00:31:20.240] Timer tick, asking Buildbucket for the build status
[00:31:21.431] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:21.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:80:0) after 1m0s
[00:32:21.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:80:0)
[00:32:21.546] Timer tick, asking Buildbucket for the build status
[00:32:23.164] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:23.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:81:0) after 1m0s
[00:33:23.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:81:0)
[00:33:23.267] Timer tick, asking Buildbucket for the build status
[00:33:23.492] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:23.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:82:0) after 1m0s
[00:34:23.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:82:0)
[00:34:23.531] Timer tick, asking Buildbucket for the build status
[00:34:24.571] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:24.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:83:0) after 1m0s
[00:35:24.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:83:0)
[00:35:24.626] Timer tick, asking Buildbucket for the build status
[00:35:24.750] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:24.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:84:0) after 1m0s
[00:36:24.822] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:84:0)
[00:36:24.822] Timer tick, asking Buildbucket for the build status
[00:36:24.950] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:24.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:85:0) after 1m0s
[00:37:25.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:85:0)
[00:37:25.000] Timer tick, asking Buildbucket for the build status
[00:37:25.402] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:25.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:86:0) after 1m0s
[00:38:25.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:86:0)
[00:38:25.486] Timer tick, asking Buildbucket for the build status
[00:38:25.591] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:25.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:87:0) after 1m0s
[00:39:25.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:87:0)
[00:39:25.612] Timer tick, asking Buildbucket for the build status
[00:39:25.871] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:25.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:88:0) after 1m0s
[00:40:25.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:88:0)
[00:40:25.942] Timer tick, asking Buildbucket for the build status
[00:40:26.064] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:26.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:89:0) after 1m0s
[00:41:26.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:89:0)
[00:41:26.268] Timer tick, asking Buildbucket for the build status
[00:41:26.538] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:26.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:90:0) after 1m0s
[00:42:26.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:90:0)
[00:42:26.742] Timer tick, asking Buildbucket for the build status
[00:42:27.111] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:27.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:91:0) after 1m0s
[00:43:27.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:91:0)
[00:43:27.639] Timer tick, asking Buildbucket for the build status
[00:43:27.775] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:27.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:92:0) after 1m0s
[00:44:28.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:92:0)
[00:44:28.078] Timer tick, asking Buildbucket for the build status
[00:44:28.289] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:28.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:93:0) after 1m0s
[00:45:28.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:93:0)
[00:45:28.291] Timer tick, asking Buildbucket for the build status
[00:45:29.191] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:29.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:94:0) after 1m0s
[00:46:29.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:94:0)
[00:46:29.374] Timer tick, asking Buildbucket for the build status
[00:46:30.164] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:30.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:95:0) after 1m0s
[00:47:30.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:95:0)
[00:47:30.499] Timer tick, asking Buildbucket for the build status
[00:47:30.856] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:30.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:96:0) after 1m0s
[00:48:31.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:96:0)
[00:48:31.626] Timer tick, asking Buildbucket for the build status
[00:48:31.950] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:31.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:97:0) after 1m0s
[00:49:31.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:97:0)
[00:49:31.972] Timer tick, asking Buildbucket for the build status
[00:49:34.387] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:34.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:98:0) after 1m0s
[00:50:34.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:98:0)
[00:50:34.540] Timer tick, asking Buildbucket for the build status
[00:50:34.919] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:34.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:99:0) after 1m0s
[00:51:35.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:99:0)
[00:51:35.059] Timer tick, asking Buildbucket for the build status
[00:51:35.819] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:35.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:100:0) after 1m0s
[00:52:36.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:100:0)
[00:52:36.071] Timer tick, asking Buildbucket for the build status
[00:52:36.368] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:36.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:101:0) after 1m0s
[00:53:36.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:101:0)
[00:53:36.500] Timer tick, asking Buildbucket for the build status
[00:53:36.820] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:36.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:102:0) after 1m0s
[00:54:36.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:102:0)
[00:54:36.972] Timer tick, asking Buildbucket for the build status
[00:54:37.223] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:37.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:103:0) after 1m0s
[00:55:37.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:103:0)
[00:55:37.460] Timer tick, asking Buildbucket for the build status
[00:55:37.630] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:37.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:104:0) after 1m0s
[00:56:37.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:104:0)
[00:56:37.804] Timer tick, asking Buildbucket for the build status
[00:56:38.916] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:38.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:105:0) after 1m0s
[00:57:39.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:105:0)
[00:57:39.128] Timer tick, asking Buildbucket for the build status
[00:57:39.349] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:39.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:106:0) after 1m0s
[00:58:39.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:106:0)
[00:58:39.428] Timer tick, asking Buildbucket for the build status
[00:58:39.816] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:39.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:107:0) after 1m0s
[00:59:39.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:107:0)
[00:59:39.951] Timer tick, asking Buildbucket for the build status
[00:59:40.246] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:40.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:108:0) after 1m0s
[01:00:40.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:108:0)
[01:00:40.488] Timer tick, asking Buildbucket for the build status
[01:00:40.651] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:40.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:109:0) after 1m0s
[01:01:40.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:109:0)
[01:01:40.722] Timer tick, asking Buildbucket for the build status
[01:01:41.443] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:41.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:110:0) after 1m0s
[01:02:41.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:110:0)
[01:02:41.668] Timer tick, asking Buildbucket for the build status
[01:02:42.078] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:42.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:111:0) after 1m0s
[01:03:42.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:111:0)
[01:03:42.224] Timer tick, asking Buildbucket for the build status
[01:03:42.622] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:42.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:112:0) after 1m0s
[01:04:42.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:112:0)
[01:04:42.667] Timer tick, asking Buildbucket for the build status
[01:04:42.882] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:42.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:113:0) after 1m0s
[01:05:42.919] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:113:0)
[01:05:42.919] Timer tick, asking Buildbucket for the build status
[01:05:43.985] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:43.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:114:0) after 1m0s
[01:06:44.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:114:0)
[01:06:44.104] Timer tick, asking Buildbucket for the build status
[01:06:44.925] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:44.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:115:0) after 1m0s
[01:07:44.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:115:0)
[01:07:44.965] Timer tick, asking Buildbucket for the build status
[01:07:45.830] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:45.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:116:0) after 1m0s
[01:08:45.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:116:0)
[01:08:45.838] Timer tick, asking Buildbucket for the build status
[01:08:46.197] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:46.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:117:0) after 1m0s
[01:09:46.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:117:0)
[01:09:46.363] Timer tick, asking Buildbucket for the build status
[01:09:46.475] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:46.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:118:0) after 1m0s
[01:10:46.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:118:0)
[01:10:46.583] Timer tick, asking Buildbucket for the build status
[01:10:47.034] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:47.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:119:0) after 1m0s
[01:11:47.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:119:0)
[01:11:47.092] Timer tick, asking Buildbucket for the build status
[01:11:47.340] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:47.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:120:0) after 1m0s
[01:12:47.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:120:0)
[01:12:47.505] Timer tick, asking Buildbucket for the build status
[01:12:47.632] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:47.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:121:0) after 1m0s
[01:13:47.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:121:0)
[01:13:47.727] Timer tick, asking Buildbucket for the build status
[01:13:47.980] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:47.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:122:0) after 1m0s
[01:14:48.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:122:0)
[01:14:48.379] Timer tick, asking Buildbucket for the build status
[01:14:48.460] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:48.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:123:0) after 1m0s
[01:15:48.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:123:0)
[01:15:48.657] Timer tick, asking Buildbucket for the build status
[01:15:48.911] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:48.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:124:0) after 1m0s
[01:16:49.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:124:0)
[01:16:49.131] Timer tick, asking Buildbucket for the build status
[01:16:50.830] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:50.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:125:0) after 1m0s
[01:17:51.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:125:0)
[01:17:51.428] Timer tick, asking Buildbucket for the build status
[01:17:51.535] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:51.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:126:0) after 1m0s
[01:18:51.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:126:0)
[01:18:51.628] Timer tick, asking Buildbucket for the build status
[01:18:52.081] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:52.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:127:0) after 1m0s
[01:19:52.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:127:0)
[01:19:52.198] Timer tick, asking Buildbucket for the build status
[01:19:52.359] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:52.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:128:0) after 1m0s
[01:20:52.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:128:0)
[01:20:52.535] Timer tick, asking Buildbucket for the build status
[01:20:52.826] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:52.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:129:0) after 1m0s
[01:21:53.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:129:0)
[01:21:53.037] Timer tick, asking Buildbucket for the build status
[01:21:53.180] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:53.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:130:0) after 1m0s
[01:22:53.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:130:0)
[01:22:53.279] Timer tick, asking Buildbucket for the build status
[01:22:53.694] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:53.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:131:0) after 1m0s
[01:23:53.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:131:0)
[01:23:53.789] Timer tick, asking Buildbucket for the build status
[01:23:53.899] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:53.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:132:0) after 1m0s
[01:24:54.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:132:0)
[01:24:54.218] Timer tick, asking Buildbucket for the build status
[01:24:54.416] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:54.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:133:0) after 1m0s
[01:25:54.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:133:0)
[01:25:54.967] Timer tick, asking Buildbucket for the build status
[01:25:55.377] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:55.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:134:0) after 1m0s
[01:26:55.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:134:0)
[01:26:55.496] Timer tick, asking Buildbucket for the build status
[01:26:55.827] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:55.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:135:0) after 1m0s
[01:27:56.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:135:0)
[01:27:56.147] Timer tick, asking Buildbucket for the build status
[01:27:56.517] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:56.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:136:0) after 1m0s
[01:28:56.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:136:0)
[01:28:56.686] Timer tick, asking Buildbucket for the build status
[01:28:56.841] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:56.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:137:0) after 1m0s
[01:29:56.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:137:0)
[01:29:56.964] Timer tick, asking Buildbucket for the build status
[01:29:57.118] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:57.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:138:0) after 1m0s
[01:30:57.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:138:0)
[01:30:57.203] Timer tick, asking Buildbucket for the build status
[01:30:57.378] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:57.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:139:0) after 1m0s
[01:31:57.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:139:0)
[01:31:57.432] Timer tick, asking Buildbucket for the build status
[01:31:57.894] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:57.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:140:0) after 1m0s
[01:32:57.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:140:0)
[01:32:57.994] Timer tick, asking Buildbucket for the build status
[01:32:58.552] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:58.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:141:0) after 1m0s
[01:33:58.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:141:0)
[01:33:58.626] Timer tick, asking Buildbucket for the build status
[01:33:58.878] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:58.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:142:0) after 1m0s
[01:34:58.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:142:0)
[01:34:58.926] Timer tick, asking Buildbucket for the build status
[01:34:59.133] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:59.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:143:0) after 1m0s
[01:35:59.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:143:0)
[01:35:59.184] Timer tick, asking Buildbucket for the build status
[01:35:59.387] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:59.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:144:0) after 1m0s
[01:36:59.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:144:0)
[01:36:59.507] Timer tick, asking Buildbucket for the build status
[01:36:59.621] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:59.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:145:0) after 1m0s
[01:37:59.710] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:145:0)
[01:37:59.710] Timer tick, asking Buildbucket for the build status
[01:38:00.112] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:00.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:146:0) after 1m0s
[01:39:00.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:146:0)
[01:39:00.254] Timer tick, asking Buildbucket for the build status
[01:39:00.375] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:00.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:147:0) after 1m0s
[01:40:00.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:147:0)
[01:40:00.386] Timer tick, asking Buildbucket for the build status
[01:40:00.490] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:00.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:148:0) after 1m0s
[01:41:00.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:148:0)
[01:41:00.575] Timer tick, asking Buildbucket for the build status
[01:41:00.762] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:00.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:149:0) after 1m0s
[01:42:00.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:149:0)
[01:42:00.821] Timer tick, asking Buildbucket for the build status
[01:42:00.933] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:00.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:150:0) after 1m0s
[01:43:01.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:150:0)
[01:43:01.044] Timer tick, asking Buildbucket for the build status
[01:43:01.400] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:01.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:151:0) after 1m0s
[01:44:01.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:151:0)
[01:44:01.440] Timer tick, asking Buildbucket for the build status
[01:44:01.957] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:01.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:152:0) after 1m0s
[01:45:01.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:152:0)
[01:45:01.980] Timer tick, asking Buildbucket for the build status
[01:45:02.084] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:02.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:153:0) after 1m0s
[01:46:02.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:153:0)
[01:46:02.103] Timer tick, asking Buildbucket for the build status
[01:46:02.543] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:02.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:154:0) after 1m0s
[01:47:02.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:154:0)
[01:47:02.569] Timer tick, asking Buildbucket for the build status
[01:47:02.981] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:02.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:155:0) after 1m0s
[01:48:03.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:155:0)
[01:48:03.015] Timer tick, asking Buildbucket for the build status
[01:48:03.132] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:03.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:156:0) after 1m0s
[01:49:03.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:156:0)
[01:49:03.156] Timer tick, asking Buildbucket for the build status
[01:49:03.347] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:03.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:157:0) after 1m0s
[01:50:03.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:157:0)
[01:50:03.374] Timer tick, asking Buildbucket for the build status
[01:50:03.641] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:03.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:158:0) after 1m0s
[01:51:03.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:158:0)
[01:51:03.686] Timer tick, asking Buildbucket for the build status
[01:51:03.828] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:03.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:159:0) after 1m0s
[01:52:03.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:159:0)
[01:52:03.868] Timer tick, asking Buildbucket for the build status
[01:52:04.269] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:04.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:160:0) after 1m0s
[01:53:04.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:160:0)
[01:53:04.344] Timer tick, asking Buildbucket for the build status
[01:53:04.792] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:04.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:161:0) after 1m0s
[01:54:04.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:161:0)
[01:54:04.789] Timer tick, asking Buildbucket for the build status
[01:54:05.096] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:05.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:162:0) after 1m0s
[01:55:05.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:162:0)
[01:55:05.220] Timer tick, asking Buildbucket for the build status
[01:55:05.497] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:05.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:163:0) after 1m0s
[01:56:05.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:163:0)
[01:56:05.596] Timer tick, asking Buildbucket for the build status
[01:56:05.733] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:05.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:164:0) after 1m0s
[01:57:05.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:164:0)
[01:57:05.753] Timer tick, asking Buildbucket for the build status
[01:57:05.911] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:05.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:165:0) after 1m0s
[01:58:06.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:165:0)
[01:58:06.043] Timer tick, asking Buildbucket for the build status
[01:58:06.142] Build 8886535481657987760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:06.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:166:0) after 1m0s
[01:58:09.563] Received PubSub notification, asking Buildbucket for the build status
[01:58:09.701] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:06.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:166:0)
[01:59:06.237] Timer tick, asking Buildbucket for the build status
[01:59:06.389] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:06.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:168:0) after 1m0s
[02:00:06.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:168:0)
[02:00:06.454] Timer tick, asking Buildbucket for the build status
[02:00:06.614] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:06.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:169:0) after 1m0s
[02:01:06.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:169:0)
[02:01:06.654] Timer tick, asking Buildbucket for the build status
[02:01:06.884] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:06.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:170:0) after 1m0s
[02:02:06.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:170:0)
[02:02:06.933] Timer tick, asking Buildbucket for the build status
[02:02:07.112] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:07.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:171:0) after 1m0s
[02:03:07.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:171:0)
[02:03:07.156] Timer tick, asking Buildbucket for the build status
[02:03:07.785] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:07.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:172:0) after 1m0s
[02:04:07.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:172:0)
[02:04:07.891] Timer tick, asking Buildbucket for the build status
[02:04:08.125] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:08.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:173:0) after 1m0s
[02:05:08.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:173:0)
[02:05:08.468] Timer tick, asking Buildbucket for the build status
[02:05:08.920] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:08.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:174:0) after 1m0s
[02:06:09.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:174:0)
[02:06:09.060] Timer tick, asking Buildbucket for the build status
[02:06:09.425] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:09.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:175:0) after 1m0s
[02:07:09.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:175:0)
[02:07:09.546] Timer tick, asking Buildbucket for the build status
[02:07:10.497] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:10.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:176:0) after 1m0s
[02:08:10.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:176:0)
[02:08:10.673] Timer tick, asking Buildbucket for the build status
[02:08:10.921] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:10.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:177:0) after 1m0s
[02:09:10.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:177:0)
[02:09:10.948] Timer tick, asking Buildbucket for the build status
[02:09:11.944] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:11.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:178:0) after 1m0s
[02:10:11.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:178:0)
[02:10:11.989] Timer tick, asking Buildbucket for the build status
[02:10:12.213] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:179:0) after 1m0s
[02:11:12.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:179:0)
[02:11:12.313] Timer tick, asking Buildbucket for the build status
[02:11:12.543] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:12.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:180:0) after 1m0s
[02:12:12.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:180:0)
[02:12:12.719] Timer tick, asking Buildbucket for the build status
[02:12:13.196] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:13.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:181:0) after 1m0s
[02:13:13.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:181:0)
[02:13:13.286] Timer tick, asking Buildbucket for the build status
[02:13:13.395] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:182:0) after 1m0s
[02:14:13.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:182:0)
[02:14:13.616] Timer tick, asking Buildbucket for the build status
[02:14:13.715] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:183:0) after 1m0s
[02:15:14.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:183:0)
[02:15:14.250] Timer tick, asking Buildbucket for the build status
[02:15:14.656] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:184:0) after 1m0s
[02:16:14.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:184:0)
[02:16:14.881] Timer tick, asking Buildbucket for the build status
[02:16:15.098] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:15.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:185:0) after 1m0s
[02:17:15.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:185:0)
[02:17:15.342] Timer tick, asking Buildbucket for the build status
[02:17:15.486] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:15.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:186:0) after 1m0s
[02:18:15.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:186:0)
[02:18:15.704] Timer tick, asking Buildbucket for the build status
[02:18:15.847] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:15.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:187:0) after 1m0s
[02:19:15.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:187:0)
[02:19:15.949] Timer tick, asking Buildbucket for the build status
[02:19:16.404] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:16.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:188:0) after 1m0s
[02:20:16.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:188:0)
[02:20:16.417] Timer tick, asking Buildbucket for the build status
[02:20:16.548] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:16.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:189:0) after 1m0s
[02:21:16.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:189:0)
[02:21:16.687] Timer tick, asking Buildbucket for the build status
[02:21:16.925] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:16.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:190:0) after 1m0s
[02:22:17.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:190:0)
[02:22:17.001] Timer tick, asking Buildbucket for the build status
[02:22:17.337] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:17.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:191:0) after 1m0s
[02:23:17.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:191:0)
[02:23:17.397] Timer tick, asking Buildbucket for the build status
[02:23:17.601] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:17.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:192:0) after 1m0s
[02:24:17.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:192:0)
[02:24:17.659] Timer tick, asking Buildbucket for the build status
[02:24:18.089] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:18.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:193:0) after 1m0s
[02:25:18.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:193:0)
[02:25:18.161] Timer tick, asking Buildbucket for the build status
[02:25:18.310] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:194:0) after 1m0s
[02:26:18.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:194:0)
[02:26:18.391] Timer tick, asking Buildbucket for the build status
[02:26:18.769] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:18.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:195:0) after 1m0s
[02:27:18.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:195:0)
[02:27:18.991] Timer tick, asking Buildbucket for the build status
[02:27:19.126] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:19.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:196:0) after 1m0s
[02:28:19.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:196:0)
[02:28:19.251] Timer tick, asking Buildbucket for the build status
[02:28:19.594] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:19.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:197:0) after 1m0s
[02:29:19.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:197:0)
[02:29:19.774] Timer tick, asking Buildbucket for the build status
[02:29:20.028] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:20.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:198:0) after 1m0s
[02:30:20.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:198:0)
[02:30:20.242] Timer tick, asking Buildbucket for the build status
[02:30:20.482] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:20.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:199:0) after 1m0s
[02:31:20.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:199:0)
[02:31:20.667] Timer tick, asking Buildbucket for the build status
[02:31:21.055] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:21.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:200:0) after 1m0s
[02:32:21.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:200:0)
[02:32:21.131] Timer tick, asking Buildbucket for the build status
[02:32:21.395] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:21.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:201:0) after 1m0s
[02:33:21.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:201:0)
[02:33:21.387] Timer tick, asking Buildbucket for the build status
[02:33:21.488] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:21.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:202:0) after 1m0s
[02:34:21.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:202:0)
[02:34:21.566] Timer tick, asking Buildbucket for the build status
[02:34:21.657] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:21.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:203:0) after 1m0s
[02:35:21.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:203:0)
[02:35:21.682] Timer tick, asking Buildbucket for the build status
[02:35:21.851] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:21.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:204:0) after 1m0s
[02:36:21.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:204:0)
[02:36:21.858] Timer tick, asking Buildbucket for the build status
[02:36:22.923] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:22.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:205:0) after 1m0s
[02:37:22.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:205:0)
[02:37:22.981] Timer tick, asking Buildbucket for the build status
[02:37:23.173] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:23.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:206:0) after 1m0s
[02:38:23.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:206:0)
[02:38:23.221] Timer tick, asking Buildbucket for the build status
[02:38:23.337] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:23.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:207:0) after 1m0s
[02:39:23.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:207:0)
[02:39:23.455] Timer tick, asking Buildbucket for the build status
[02:39:23.624] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:208:0) after 1m0s
[02:40:23.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:208:0)
[02:40:23.762] Timer tick, asking Buildbucket for the build status
[02:40:23.930] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:23.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:209:0) after 1m0s
[02:41:24.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:209:0)
[02:41:24.101] Timer tick, asking Buildbucket for the build status
[02:41:25.532] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:25.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:210:0) after 1m0s
[02:42:25.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:210:0)
[02:42:25.622] Timer tick, asking Buildbucket for the build status
[02:42:25.979] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:25.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:211:0) after 1m0s
[02:43:26.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:211:0)
[02:43:26.003] Timer tick, asking Buildbucket for the build status
[02:43:26.302] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:26.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:212:0) after 1m0s
[02:44:26.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:212:0)
[02:44:26.353] Timer tick, asking Buildbucket for the build status
[02:44:26.508] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:26.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:213:0) after 1m0s
[02:45:26.520] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:213:0)
[02:45:26.520] Timer tick, asking Buildbucket for the build status
[02:45:26.911] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:26.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:214:0) after 1m0s
[02:46:27.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:214:0)
[02:46:27.048] Timer tick, asking Buildbucket for the build status
[02:46:27.279] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:27.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:215:0) after 1m0s
[02:47:27.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:215:0)
[02:47:27.303] Timer tick, asking Buildbucket for the build status
[02:47:27.494] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:27.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:216:0) after 1m0s
[02:48:27.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:216:0)
[02:48:27.553] Timer tick, asking Buildbucket for the build status
[02:48:27.689] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:27.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:217:0) after 1m0s
[02:49:27.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:217:0)
[02:49:27.743] Timer tick, asking Buildbucket for the build status
[02:49:28.063] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:28.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:218:0) after 1m0s
[02:50:28.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:218:0)
[02:50:28.097] Timer tick, asking Buildbucket for the build status
[02:50:28.240] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:28.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:219:0) after 1m0s
[02:51:28.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:219:0)
[02:51:28.274] Timer tick, asking Buildbucket for the build status
[02:51:28.631] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:28.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:220:0) after 1m0s
[02:52:28.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:220:0)
[02:52:28.685] Timer tick, asking Buildbucket for the build status
[02:52:29.073] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:29.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:221:0) after 1m0s
[02:53:29.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:221:0)
[02:53:29.110] Timer tick, asking Buildbucket for the build status
[02:53:29.455] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:29.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:222:0) after 1m0s
[02:54:29.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:222:0)
[02:54:29.600] Timer tick, asking Buildbucket for the build status
[02:54:32.090] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:32.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:223:0) after 1m0s
[02:55:32.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:223:0)
[02:55:32.149] Timer tick, asking Buildbucket for the build status
[02:55:32.317] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:32.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:224:0) after 1m0s
[02:56:32.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:224:0)
[02:56:32.404] Timer tick, asking Buildbucket for the build status
[02:56:34.134] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:34.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:225:0) after 1m0s
[02:57:34.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:225:0)
[02:57:34.165] Timer tick, asking Buildbucket for the build status
[02:57:34.263] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:34.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:226:0) after 1m0s
[02:58:34.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:226:0)
[02:58:34.312] Timer tick, asking Buildbucket for the build status
[02:58:34.473] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:34.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:227:0) after 1m0s
[02:59:34.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:227:0)
[02:59:34.534] Timer tick, asking Buildbucket for the build status
[02:59:34.914] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:34.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:228:0) after 1m0s
[03:00:34.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:228:0)
[03:00:34.937] Timer tick, asking Buildbucket for the build status
[03:00:35.176] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:35.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:229:0) after 1m0s
[03:01:35.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:229:0)
[03:01:35.267] Timer tick, asking Buildbucket for the build status
[03:01:35.941] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:35.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:230:0) after 1m0s
[03:02:35.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:230:0)
[03:02:35.964] Timer tick, asking Buildbucket for the build status
[03:02:36.209] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:36.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:231:0) after 1m0s
[03:03:36.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:231:0)
[03:03:36.309] Timer tick, asking Buildbucket for the build status
[03:03:36.600] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:36.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:232:0) after 1m0s
[03:04:36.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:232:0)
[03:04:36.748] Timer tick, asking Buildbucket for the build status
[03:04:36.929] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:36.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:233:0) after 1m0s
[03:05:36.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:233:0)
[03:05:36.955] Timer tick, asking Buildbucket for the build status
[03:05:37.080] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:37.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:234:0) after 1m0s
[03:06:37.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:234:0)
[03:06:37.186] Timer tick, asking Buildbucket for the build status
[03:06:37.655] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:37.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:235:0) after 1m0s
[03:07:37.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:235:0)
[03:07:37.736] Timer tick, asking Buildbucket for the build status
[03:07:37.839] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:37.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:236:0) after 1m0s
[03:08:37.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:236:0)
[03:08:37.903] Timer tick, asking Buildbucket for the build status
[03:08:38.264] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:38.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:237:0) after 1m0s
[03:09:38.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:237:0)
[03:09:38.342] Timer tick, asking Buildbucket for the build status
[03:09:38.600] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:238:0) after 1m0s
[03:10:38.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:238:0)
[03:10:38.719] Timer tick, asking Buildbucket for the build status
[03:10:39.251] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:39.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:239:0) after 1m0s
[03:11:39.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:239:0)
[03:11:39.256] Timer tick, asking Buildbucket for the build status
[03:11:39.439] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:39.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:240:0) after 1m0s
[03:12:39.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:240:0)
[03:12:39.438] Timer tick, asking Buildbucket for the build status
[03:12:39.641] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:39.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:241:0) after 1m0s
[03:13:39.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:241:0)
[03:13:39.674] Timer tick, asking Buildbucket for the build status
[03:13:40.014] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:40.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:242:0) after 1m0s
[03:14:40.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:242:0)
[03:14:40.124] Timer tick, asking Buildbucket for the build status
[03:14:40.288] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:40.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:243:0) after 1m0s
[03:15:40.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:243:0)
[03:15:40.383] Timer tick, asking Buildbucket for the build status
[03:15:40.564] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:40.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:244:0) after 1m0s
[03:16:40.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:244:0)
[03:16:40.659] Timer tick, asking Buildbucket for the build status
[03:16:40.945] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:40.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:245:0) after 1m0s
[03:17:40.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:245:0)
[03:17:40.994] Timer tick, asking Buildbucket for the build status
[03:17:41.195] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:41.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:246:0) after 1m0s
[03:18:41.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:246:0)
[03:18:41.276] Timer tick, asking Buildbucket for the build status
[03:18:41.404] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:41.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:247:0) after 1m0s
[03:19:41.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:247:0)
[03:19:41.415] Timer tick, asking Buildbucket for the build status
[03:19:41.543] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:41.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:248:0) after 1m0s
[03:20:41.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:248:0)
[03:20:41.589] Timer tick, asking Buildbucket for the build status
[03:20:41.777] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:41.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:249:0) after 1m0s
[03:21:41.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:249:0)
[03:21:41.781] Timer tick, asking Buildbucket for the build status
[03:21:42.119] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:42.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:250:0) after 1m0s
[03:22:42.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:250:0)
[03:22:42.145] Timer tick, asking Buildbucket for the build status
[03:22:42.478] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:42.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:251:0) after 1m0s
[03:23:42.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:251:0)
[03:23:42.591] Timer tick, asking Buildbucket for the build status
[03:23:42.734] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:42.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:252:0) after 1m0s
[03:24:42.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:252:0)
[03:24:42.835] Timer tick, asking Buildbucket for the build status
[03:24:43.067] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:43.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:253:0) after 1m0s
[03:25:43.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:253:0)
[03:25:43.148] Timer tick, asking Buildbucket for the build status
[03:25:43.234] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:43.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:254:0) after 1m0s
[03:26:43.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:254:0)
[03:26:43.294] Timer tick, asking Buildbucket for the build status
[03:26:43.435] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:43.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:255:0) after 1m0s
[03:27:43.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:255:0)
[03:27:43.493] Timer tick, asking Buildbucket for the build status
[03:27:43.678] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:43.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:256:0) after 1m0s
[03:28:43.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:256:0)
[03:28:43.719] Timer tick, asking Buildbucket for the build status
[03:28:43.822] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:43.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:257:0) after 1m0s
[03:29:43.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:257:0)
[03:29:43.847] Timer tick, asking Buildbucket for the build status
[03:29:44.015] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:44.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:258:0) after 1m0s
[03:30:44.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:258:0)
[03:30:44.169] Timer tick, asking Buildbucket for the build status
[03:30:44.301] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:44.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:259:0) after 1m0s
[03:31:44.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:259:0)
[03:31:44.363] Timer tick, asking Buildbucket for the build status
[03:31:44.598] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:44.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:260:0) after 1m0s
[03:32:44.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:260:0)
[03:32:44.749] Timer tick, asking Buildbucket for the build status
[03:32:44.893] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:44.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:261:0) after 1m0s
[03:33:44.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:261:0)
[03:33:44.951] Timer tick, asking Buildbucket for the build status
[03:33:45.165] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:45.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:262:0) after 1m0s
[03:34:45.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:262:0)
[03:34:45.243] Timer tick, asking Buildbucket for the build status
[03:34:45.435] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:45.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:263:0) after 1m0s
[03:35:45.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:263:0)
[03:35:45.570] Timer tick, asking Buildbucket for the build status
[03:35:45.688] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:45.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:264:0) after 1m0s
[03:36:45.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:264:0)
[03:36:45.808] Timer tick, asking Buildbucket for the build status
[03:36:46.145] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:46.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:265:0) after 1m0s
[03:37:46.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:265:0)
[03:37:46.149] Timer tick, asking Buildbucket for the build status
[03:37:46.379] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:46.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:266:0) after 1m0s
[03:38:46.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:266:0)
[03:38:46.470] Timer tick, asking Buildbucket for the build status
[03:38:46.782] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:46.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:267:0) after 1m0s
[03:39:46.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:267:0)
[03:39:46.840] Timer tick, asking Buildbucket for the build status
[03:39:46.992] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:46.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:268:0) after 1m0s
[03:40:47.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:268:0)
[03:40:47.094] Timer tick, asking Buildbucket for the build status
[03:40:47.319] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:47.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:269:0) after 1m0s
[03:41:47.359] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:269:0)
[03:41:47.359] Timer tick, asking Buildbucket for the build status
[03:41:47.723] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:47.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:270:0) after 1m0s
[03:42:47.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:270:0)
[03:42:47.742] Timer tick, asking Buildbucket for the build status
[03:42:49.266] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:49.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:271:0) after 1m0s
[03:43:49.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:271:0)
[03:43:49.281] Timer tick, asking Buildbucket for the build status
[03:43:49.552] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:49.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:272:0) after 1m0s
[03:44:49.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:272:0)
[03:44:49.597] Timer tick, asking Buildbucket for the build status
[03:44:49.691] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:49.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:273:0) after 1m0s
[03:45:49.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:273:0)
[03:45:49.760] Timer tick, asking Buildbucket for the build status
[03:45:50.167] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:50.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:274:0) after 1m0s
[03:46:50.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:274:0)
[03:46:50.198] Timer tick, asking Buildbucket for the build status
[03:46:50.340] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:50.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:275:0) after 1m0s
[03:47:50.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:275:0)
[03:47:50.396] Timer tick, asking Buildbucket for the build status
[03:47:50.503] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:50.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:276:0) after 1m0s
[03:48:50.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:276:0)
[03:48:50.612] Timer tick, asking Buildbucket for the build status
[03:48:50.751] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:50.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:277:0) after 1m0s
[03:49:50.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:277:0)
[03:49:50.790] Timer tick, asking Buildbucket for the build status
[03:49:50.953] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:50.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:278:0) after 1m0s
[03:50:50.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:278:0)
[03:50:50.978] Timer tick, asking Buildbucket for the build status
[03:50:51.073] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:51.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:279:0) after 1m0s
[03:51:51.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:279:0)
[03:51:51.108] Timer tick, asking Buildbucket for the build status
[03:51:51.209] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:51.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:280:0) after 1m0s
[03:52:51.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:280:0)
[03:52:51.259] Timer tick, asking Buildbucket for the build status
[03:52:51.405] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:51.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:281:0) after 1m0s
[03:53:51.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:281:0)
[03:53:51.434] Timer tick, asking Buildbucket for the build status
[03:53:51.519] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:51.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:282:0) after 1m0s
[03:54:51.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:282:0)
[03:54:51.589] Timer tick, asking Buildbucket for the build status
[03:54:51.702] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:51.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:283:0) after 1m0s
[03:55:51.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:283:0)
[03:55:51.789] Timer tick, asking Buildbucket for the build status
[03:55:51.875] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:51.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:284:0) after 1m0s
[03:56:51.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:284:0)
[03:56:51.922] Timer tick, asking Buildbucket for the build status
[03:56:52.332] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:52.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:285:0) after 1m0s
[03:57:52.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:285:0)
[03:57:52.453] Timer tick, asking Buildbucket for the build status
[03:57:52.683] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:52.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:286:0) after 1m0s
[03:58:52.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:286:0)
[03:58:52.718] Timer tick, asking Buildbucket for the build status
[03:58:52.986] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:52.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:287:0) after 1m0s
[03:59:53.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:287:0)
[03:59:53.019] Timer tick, asking Buildbucket for the build status
[03:59:53.430] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:53.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:288:0) after 1m0s
[04:00:53.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:288:0)
[04:00:53.625] Timer tick, asking Buildbucket for the build status
[04:00:53.739] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:53.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:289:0) after 1m0s
[04:01:53.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:289:0)
[04:01:53.776] Timer tick, asking Buildbucket for the build status
[04:01:54.154] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:54.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:290:0) after 1m0s
[04:02:54.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:290:0)
[04:02:54.177] Timer tick, asking Buildbucket for the build status
[04:02:54.832] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:54.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:291:0) after 1m0s
[04:03:54.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:291:0)
[04:03:54.941] Timer tick, asking Buildbucket for the build status
[04:03:55.088] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:55.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:292:0) after 1m0s
[04:04:55.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:292:0)
[04:04:55.111] Timer tick, asking Buildbucket for the build status
[04:04:55.281] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:55.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:293:0) after 1m0s
[04:05:55.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:293:0)
[04:05:55.413] Timer tick, asking Buildbucket for the build status
[04:05:55.593] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:294:0) after 1m0s
[04:06:55.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:294:0)
[04:06:55.661] Timer tick, asking Buildbucket for the build status
[04:06:56.082] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:56.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:295:0) after 1m0s
[04:07:56.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:295:0)
[04:07:56.158] Timer tick, asking Buildbucket for the build status
[04:07:56.264] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:56.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:296:0) after 1m0s
[04:08:56.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:296:0)
[04:08:56.282] Timer tick, asking Buildbucket for the build status
[04:08:56.406] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:56.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:297:0) after 1m0s
[04:09:56.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:297:0)
[04:09:56.457] Timer tick, asking Buildbucket for the build status
[04:09:56.558] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:56.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:298:0) after 1m0s
[04:10:56.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:298:0)
[04:10:56.625] Timer tick, asking Buildbucket for the build status
[04:10:56.779] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:56.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:299:0) after 1m0s
[04:11:56.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:299:0)
[04:11:56.851] Timer tick, asking Buildbucket for the build status
[04:11:56.994] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:56.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:300:0) after 1m0s
[04:12:57.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:300:0)
[04:12:57.081] Timer tick, asking Buildbucket for the build status
[04:12:57.190] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:57.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:301:0) after 1m0s
[04:13:57.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:301:0)
[04:13:57.231] Timer tick, asking Buildbucket for the build status
[04:13:57.402] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:57.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:302:0) after 1m0s
[04:14:57.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:302:0)
[04:14:57.429] Timer tick, asking Buildbucket for the build status
[04:14:57.523] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:57.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:303:0) after 1m0s
[04:15:57.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:303:0)
[04:15:57.554] Timer tick, asking Buildbucket for the build status
[04:15:57.820] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:57.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:304:0) after 1m0s
[04:16:57.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:304:0)
[04:16:57.847] Timer tick, asking Buildbucket for the build status
[04:16:58.001] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:58.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:305:0) after 1m0s
[04:17:58.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:305:0)
[04:17:58.036] Timer tick, asking Buildbucket for the build status
[04:17:58.188] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:58.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:306:0) after 1m0s
[04:18:58.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:306:0)
[04:18:58.216] Timer tick, asking Buildbucket for the build status
[04:18:58.314] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:58.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:307:0) after 1m0s
[04:19:58.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:307:0)
[04:19:58.316] Timer tick, asking Buildbucket for the build status
[04:19:58.647] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:58.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:308:0) after 1m0s
[04:20:58.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:308:0)
[04:20:58.777] Timer tick, asking Buildbucket for the build status
[04:20:58.890] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:58.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:309:0) after 1m0s
[04:21:59.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:309:0)
[04:21:59.032] Timer tick, asking Buildbucket for the build status
[04:21:59.215] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:59.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:310:0) after 1m0s
[04:22:59.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:310:0)
[04:22:59.251] Timer tick, asking Buildbucket for the build status
[04:22:59.405] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:59.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:311:0) after 1m0s
[04:23:59.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:311:0)
[04:23:59.424] Timer tick, asking Buildbucket for the build status
[04:23:59.556] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:59.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:312:0) after 1m0s
[04:24:59.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:312:0)
[04:24:59.582] Timer tick, asking Buildbucket for the build status
[04:24:59.736] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:59.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:313:0) after 1m0s
[04:25:59.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:313:0)
[04:25:59.766] Timer tick, asking Buildbucket for the build status
[04:25:59.848] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:59.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:314:0) after 1m0s
[04:26:59.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:314:0)
[04:26:59.911] Timer tick, asking Buildbucket for the build status
[04:27:00.231] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:00.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:315:0) after 1m0s
[04:28:00.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:315:0)
[04:28:00.288] Timer tick, asking Buildbucket for the build status
[04:28:00.407] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:00.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:316:0) after 1m0s
[04:29:00.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:316:0)
[04:29:00.460] Timer tick, asking Buildbucket for the build status
[04:29:00.572] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:00.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:317:0) after 1m0s
[04:30:00.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:317:0)
[04:30:00.605] Timer tick, asking Buildbucket for the build status
[04:30:00.710] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:00.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:318:0) after 1m0s
[04:31:00.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:318:0)
[04:31:00.771] Timer tick, asking Buildbucket for the build status
[04:31:00.913] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:00.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:319:0) after 1m0s
[04:32:01.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:319:0)
[04:32:01.045] Timer tick, asking Buildbucket for the build status
[04:32:01.224] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:01.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:320:0) after 1m0s
[04:33:01.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:320:0)
[04:33:01.254] Timer tick, asking Buildbucket for the build status
[04:33:01.380] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:01.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:321:0) after 1m0s
[04:34:01.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:321:0)
[04:34:01.484] Timer tick, asking Buildbucket for the build status
[04:34:01.762] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:01.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:322:0) after 1m0s
[04:35:01.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:322:0)
[04:35:01.856] Timer tick, asking Buildbucket for the build status
[04:35:02.064] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:02.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:323:0) after 1m0s
[04:36:02.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:323:0)
[04:36:02.125] Timer tick, asking Buildbucket for the build status
[04:36:02.505] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:02.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:324:0) after 1m0s
[04:37:02.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:324:0)
[04:37:02.600] Timer tick, asking Buildbucket for the build status
[04:37:02.864] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:02.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:325:0) after 1m0s
[04:38:02.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:325:0)
[04:38:02.877] Timer tick, asking Buildbucket for the build status
[04:38:03.039] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:03.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:326:0) after 1m0s
[04:39:03.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:326:0)
[04:39:03.076] Timer tick, asking Buildbucket for the build status
[04:39:03.390] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:03.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:327:0) after 1m0s
[04:40:03.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:327:0)
[04:40:03.526] Timer tick, asking Buildbucket for the build status
[04:40:03.945] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:03.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:328:0) after 1m0s
[04:41:03.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:328:0)
[04:41:03.989] Timer tick, asking Buildbucket for the build status
[04:41:04.216] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:04.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:329:0) after 1m0s
[04:42:04.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:329:0)
[04:42:04.248] Timer tick, asking Buildbucket for the build status
[04:42:04.731] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:04.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:330:0) after 1m0s
[04:43:04.723] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:330:0)
[04:43:04.723] Timer tick, asking Buildbucket for the build status
[04:43:04.883] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:04.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:331:0) after 1m0s
[04:44:04.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:331:0)
[04:44:04.899] Timer tick, asking Buildbucket for the build status
[04:44:05.027] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:05.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:332:0) after 1m0s
[04:45:05.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:332:0)
[04:45:05.073] Timer tick, asking Buildbucket for the build status
[04:45:05.187] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:05.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:333:0) after 1m0s
[04:46:05.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:333:0)
[04:46:05.472] Timer tick, asking Buildbucket for the build status
[04:46:06.029] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:06.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:334:0) after 1m0s
[04:47:06.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:334:0)
[04:47:06.083] Timer tick, asking Buildbucket for the build status
[04:47:06.194] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:06.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:335:0) after 1m0s
[04:48:06.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:335:0)
[04:48:06.243] Timer tick, asking Buildbucket for the build status
[04:48:06.434] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:06.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:336:0) after 1m0s
[04:49:06.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:336:0)
[04:49:06.423] Timer tick, asking Buildbucket for the build status
[04:49:06.623] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:06.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:337:0) after 1m0s
[04:50:07.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:337:0)
[04:50:07.041] Timer tick, asking Buildbucket for the build status
[04:50:07.563] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:07.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:338:0) after 1m0s
[04:51:07.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:338:0)
[04:51:07.674] Timer tick, asking Buildbucket for the build status
[04:51:08.185] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:08.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:339:0) after 1m0s
[04:52:08.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:339:0)
[04:52:08.479] Timer tick, asking Buildbucket for the build status
[04:52:08.648] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:08.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:340:0) after 1m0s
[04:53:08.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:340:0)
[04:53:08.677] Timer tick, asking Buildbucket for the build status
[04:53:08.766] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:08.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:341:0) after 1m0s
[04:54:08.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:341:0)
[04:54:08.823] Timer tick, asking Buildbucket for the build status
[04:54:09.176] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:09.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:342:0) after 1m0s
[04:55:09.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:342:0)
[04:55:09.203] Timer tick, asking Buildbucket for the build status
[04:55:09.402] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:09.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:343:0) after 1m0s
[04:56:09.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:343:0)
[04:56:09.397] Timer tick, asking Buildbucket for the build status
[04:56:09.697] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:09.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:344:0) after 1m0s
[04:57:09.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:344:0)
[04:57:09.784] Timer tick, asking Buildbucket for the build status
[04:57:09.874] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:09.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:345:0) after 1m0s
[04:58:09.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:345:0)
[04:58:09.921] Timer tick, asking Buildbucket for the build status
[04:58:10.275] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:10.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:346:0) after 1m0s
[04:59:10.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:346:0)
[04:59:10.362] Timer tick, asking Buildbucket for the build status
[04:59:10.529] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:10.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:347:0) after 1m0s
[05:00:10.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:347:0)
[05:00:10.617] Timer tick, asking Buildbucket for the build status
[05:00:10.750] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:10.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:348:0) after 1m0s
[05:01:10.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:348:0)
[05:01:10.997] Timer tick, asking Buildbucket for the build status
[05:01:11.121] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:11.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:349:0) after 1m0s
[05:02:11.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:349:0)
[05:02:11.227] Timer tick, asking Buildbucket for the build status
[05:02:11.514] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:11.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:350:0) after 1m0s
[05:03:11.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:350:0)
[05:03:11.588] Timer tick, asking Buildbucket for the build status
[05:03:11.688] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:11.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:351:0) after 1m0s
[05:04:11.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:351:0)
[05:04:11.718] Timer tick, asking Buildbucket for the build status
[05:04:12.146] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:12.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:352:0) after 1m0s
[05:05:12.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:352:0)
[05:05:12.202] Timer tick, asking Buildbucket for the build status
[05:05:12.411] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:12.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:353:0) after 1m0s
[05:06:12.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:353:0)
[05:06:12.501] Timer tick, asking Buildbucket for the build status
[05:06:12.635] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:12.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:354:0) after 1m0s
[05:07:12.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:354:0)
[05:07:12.650] Timer tick, asking Buildbucket for the build status
[05:07:12.752] Build 8886535481657987760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:12.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9051965550843923600:355:0) after 1m0s
[05:07:29.947] Received PubSub notification, asking Buildbucket for the build status
[05:07:30.051] Build 8886535481657987760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:07:30.051] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1583557648827893",
    "created_by": "project:nacl",
    "created_ts": "1583536371517140",
    "failure_reason": "BUILD_FAILURE",
    "id": "8886535481657987760",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"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\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"title\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"title\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-1-p5p0\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5090, \"got_revision\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-1-p5p0\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4851-268d16b\"], \"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": "1583546284589768",
    "status": "COMPLETED",
    "status_changed_ts": "1583546288714673",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5090",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c5064fd3d8fa3ecd3ecd0b7489b0d7050b6ba8b0",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9051965550843923600",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8886535481657987760/+/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:4acaf107f83dbf10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1583557648907092",
    "url": "https://ci.chromium.org/b/8886535481657987760",
    "utcnow_ts": "1583557650043402"
  }
}
[05:07:30.051] Invocation finished in 5h54m46.678505756s with status FAILED