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

• 87b7f311d3a006f29e28a3631cc19f9f30577ac3 (5 years ago)

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

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

[15:57:38.964] New invocation is queued and will start shortly
[15:57:40.957] Starting the invocation (attempt 1)
[15:57:41.039] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:57:41.039] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:57:41.039] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9052083520292641280",
  "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\":\"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@87b7f311d3a006f29e28a3631cc19f9f30577ac3\",\"title\":\"87b7f311d3a006f29e28a3631cc19f9f30577ac3\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}}",
  "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:9052083520292641280",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
    "gitiles_ref:refs/heads/master"
  ]
}
[15:57:41.891] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1583423861183555",
    "id": "8886653457293968832",
    "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\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"title\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1583423861575204",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5088",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052083520292641280",
      "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/8886653457293968832/+/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": "1583423861575302",
    "url": "https://ci.chromium.org/b/8886653457293968832",
    "utcnow_ts": "1583423861881478"
  }
}
[15:57:41.891] Task URL: https://ci.chromium.org/b/8886653457293968832
[15:57:41.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:2:0) after 1m0s
[15:58:15.657] Received PubSub notification, asking Buildbucket for the build status
[15:58:15.758] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:41.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:2:0)
[15:58:41.870] Timer tick, asking Buildbucket for the build status
[15:58:41.936] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:41.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:4:0) after 1m0s
[15:59:41.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:4:0)
[15:59:41.957] Timer tick, asking Buildbucket for the build status
[15:59:42.181] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:42.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:5:0) after 1m0s
[16:00:42.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:5:0)
[16:00:42.328] Timer tick, asking Buildbucket for the build status
[16:00:42.584] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:42.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:6:0) after 1m0s
[16:01:42.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:6:0)
[16:01:42.640] Timer tick, asking Buildbucket for the build status
[16:01:42.892] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:42.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:7:0) after 1m0s
[16:02:42.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:7:0)
[16:02:42.924] Timer tick, asking Buildbucket for the build status
[16:02:43.286] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:43.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:8:0) after 1m0s
[16:03:43.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:8:0)
[16:03:43.307] Timer tick, asking Buildbucket for the build status
[16:03:43.675] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:43.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:9:0) after 1m0s
[16:04:43.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:9:0)
[16:04:43.742] Timer tick, asking Buildbucket for the build status
[16:04:43.977] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:43.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:10:0) after 1m0s
[16:05:43.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:10:0)
[16:05:43.978] Timer tick, asking Buildbucket for the build status
[16:05:44.079] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:44.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:11:0) after 1m0s
[16:06:44.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:11:0)
[16:06:44.156] Timer tick, asking Buildbucket for the build status
[16:06:44.459] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:44.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:12:0) after 1m0s
[16:07:44.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:12:0)
[16:07:44.523] Timer tick, asking Buildbucket for the build status
[16:07:44.652] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:44.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:13:0) after 1m0s
[16:08:44.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:13:0)
[16:08:44.948] Timer tick, asking Buildbucket for the build status
[16:08:45.347] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:45.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:14:0) after 1m0s
[16:09:45.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:14:0)
[16:09:45.401] Timer tick, asking Buildbucket for the build status
[16:09:45.562] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:45.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:15:0) after 1m0s
[16:10:45.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:15:0)
[16:10:45.617] Timer tick, asking Buildbucket for the build status
[16:10:45.709] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:45.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:16:0) after 1m0s
[16:11:45.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:16:0)
[16:11:45.790] Timer tick, asking Buildbucket for the build status
[16:11:45.930] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:45.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:17:0) after 1m0s
[16:12:46.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:17:0)
[16:12:46.112] Timer tick, asking Buildbucket for the build status
[16:12:46.432] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:46.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:18:0) after 1m0s
[16:13:46.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:18:0)
[16:13:46.481] Timer tick, asking Buildbucket for the build status
[16:13:46.532] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:46.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:19:0) after 1m0s
[16:14:46.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:19:0)
[16:14:46.572] Timer tick, asking Buildbucket for the build status
[16:14:46.775] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:46.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:20:0) after 1m0s
[16:15:46.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:20:0)
[16:15:46.873] Timer tick, asking Buildbucket for the build status
[16:15:46.985] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:46.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:21:0) after 1m0s
[16:16:47.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:21:0)
[16:16:47.001] Timer tick, asking Buildbucket for the build status
[16:16:47.102] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:47.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:22:0) after 1m0s
[16:17:47.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:22:0)
[16:17:47.165] Timer tick, asking Buildbucket for the build status
[16:17:47.266] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:47.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:23:0) after 1m0s
[16:18:47.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:23:0)
[16:18:47.289] Timer tick, asking Buildbucket for the build status
[16:18:47.986] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:47.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:24:0) after 1m0s
[16:19:48.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:24:0)
[16:19:48.105] Timer tick, asking Buildbucket for the build status
[16:19:48.296] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:48.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:25:0) after 1m0s
[16:20:48.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:25:0)
[16:20:48.489] Timer tick, asking Buildbucket for the build status
[16:20:48.624] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:48.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:26:0) after 1m0s
[16:21:48.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:26:0)
[16:21:48.617] Timer tick, asking Buildbucket for the build status
[16:21:48.702] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:48.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:27:0) after 1m0s
[16:22:48.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:27:0)
[16:22:48.987] Timer tick, asking Buildbucket for the build status
[16:22:49.353] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:49.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:28:0) after 1m0s
[16:23:49.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:28:0)
[16:23:49.529] Timer tick, asking Buildbucket for the build status
[16:23:49.713] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:49.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:29:0) after 1m0s
[16:24:49.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:29:0)
[16:24:49.849] Timer tick, asking Buildbucket for the build status
[16:24:50.212] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:50.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:30:0) after 1m0s
[16:25:50.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:30:0)
[16:25:50.290] Timer tick, asking Buildbucket for the build status
[16:25:50.568] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:50.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:31:0) after 1m0s
[16:26:50.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:31:0)
[16:26:50.602] Timer tick, asking Buildbucket for the build status
[16:26:50.886] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:50.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:32:0) after 1m0s
[16:27:51.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:32:0)
[16:27:51.002] Timer tick, asking Buildbucket for the build status
[16:27:51.120] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:51.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:33:0) after 1m0s
[16:28:51.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:33:0)
[16:28:51.155] Timer tick, asking Buildbucket for the build status
[16:28:51.257] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:51.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:34:0) after 1m0s
[16:29:51.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:34:0)
[16:29:51.331] Timer tick, asking Buildbucket for the build status
[16:29:51.610] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:51.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:35:0) after 1m0s
[16:30:51.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:35:0)
[16:30:51.674] Timer tick, asking Buildbucket for the build status
[16:30:51.773] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:51.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:36:0) after 1m0s
[16:31:51.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:36:0)
[16:31:51.825] Timer tick, asking Buildbucket for the build status
[16:31:51.968] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:51.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:37:0) after 1m0s
[16:32:52.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:37:0)
[16:32:52.061] Timer tick, asking Buildbucket for the build status
[16:32:52.169] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:52.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:38:0) after 1m0s
[16:33:52.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:38:0)
[16:33:52.242] Timer tick, asking Buildbucket for the build status
[16:33:52.328] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:52.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:39:0) after 1m0s
[16:34:52.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:39:0)
[16:34:52.519] Timer tick, asking Buildbucket for the build status
[16:34:52.648] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:52.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:40:0) after 1m0s
[16:35:52.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:40:0)
[16:35:52.805] Timer tick, asking Buildbucket for the build status
[16:35:53.084] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:53.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:41:0) after 1m0s
[16:36:53.144] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:41:0)
[16:36:53.144] Timer tick, asking Buildbucket for the build status
[16:36:53.370] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:53.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:42:0) after 1m0s
[16:37:53.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:42:0)
[16:37:53.469] Timer tick, asking Buildbucket for the build status
[16:37:53.637] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:53.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:43:0) after 1m0s
[16:38:53.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:43:0)
[16:38:53.694] Timer tick, asking Buildbucket for the build status
[16:38:54.118] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:54.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:44:0) after 1m0s
[16:39:54.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:44:0)
[16:39:54.266] Timer tick, asking Buildbucket for the build status
[16:39:54.407] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:54.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:45:0) after 1m0s
[16:40:54.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:45:0)
[16:40:54.521] Timer tick, asking Buildbucket for the build status
[16:40:54.706] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:54.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:46:0) after 1m0s
[16:41:54.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:46:0)
[16:41:54.900] Timer tick, asking Buildbucket for the build status
[16:41:55.027] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:55.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:47:0) after 1m0s
[16:42:55.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:47:0)
[16:42:55.150] Timer tick, asking Buildbucket for the build status
[16:42:55.249] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:55.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:48:0) after 1m0s
[16:43:55.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:48:0)
[16:43:55.351] Timer tick, asking Buildbucket for the build status
[16:43:55.570] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:55.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:49:0) after 1m0s
[16:44:55.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:49:0)
[16:44:55.721] Timer tick, asking Buildbucket for the build status
[16:44:55.881] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:55.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:50:0) after 1m0s
[16:45:55.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:50:0)
[16:45:55.987] Timer tick, asking Buildbucket for the build status
[16:45:56.093] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:56.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:51:0) after 1m0s
[16:46:56.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:51:0)
[16:46:56.176] Timer tick, asking Buildbucket for the build status
[16:46:56.646] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:56.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:52:0) after 1m0s
[16:47:56.693] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:52:0)
[16:47:56.693] Timer tick, asking Buildbucket for the build status
[16:47:56.834] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:56.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:53:0) after 1m0s
[16:48:57.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:53:0)
[16:48:57.071] Timer tick, asking Buildbucket for the build status
[16:48:57.227] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:57.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:54:0) after 1m0s
[16:49:57.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:54:0)
[16:49:57.494] Timer tick, asking Buildbucket for the build status
[16:49:57.603] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:57.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:55:0) after 1m0s
[16:50:57.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:55:0)
[16:50:57.616] Timer tick, asking Buildbucket for the build status
[16:50:58.086] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:58.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:56:0) after 1m0s
[16:51:58.126] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:56:0)
[16:51:58.126] Timer tick, asking Buildbucket for the build status
[16:51:58.369] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:58.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:57:0) after 1m0s
[16:52:58.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:57:0)
[16:52:58.403] Timer tick, asking Buildbucket for the build status
[16:52:58.672] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:58.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:58:0) after 1m0s
[16:53:58.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:58:0)
[16:53:58.774] Timer tick, asking Buildbucket for the build status
[16:53:58.894] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:58.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:59:0) after 1m0s
[16:54:58.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:59:0)
[16:54:58.937] Timer tick, asking Buildbucket for the build status
[16:54:59.259] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:59.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:60:0) after 1m0s
[16:55:59.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:60:0)
[16:55:59.314] Timer tick, asking Buildbucket for the build status
[16:55:59.427] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:59.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:61:0) after 1m0s
[16:56:59.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:61:0)
[16:56:59.490] Timer tick, asking Buildbucket for the build status
[16:56:59.669] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:59.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:62:0) after 1m0s
[16:57:59.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:62:0)
[16:57:59.692] Timer tick, asking Buildbucket for the build status
[16:57:59.810] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:59.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:63:0) after 1m0s
[16:58:59.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:63:0)
[16:58:59.819] Timer tick, asking Buildbucket for the build status
[16:58:59.901] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:59.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:64:0) after 1m0s
[16:59:59.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:64:0)
[16:59:59.923] Timer tick, asking Buildbucket for the build status
[17:00:00.150] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:00.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:65:0) after 1m0s
[17:01:00.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:65:0)
[17:01:00.214] Timer tick, asking Buildbucket for the build status
[17:01:00.362] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:00.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:66:0) after 1m0s
[17:02:00.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:66:0)
[17:02:00.445] Timer tick, asking Buildbucket for the build status
[17:02:00.603] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:00.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:67:0) after 1m0s
[17:03:00.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:67:0)
[17:03:00.891] Timer tick, asking Buildbucket for the build status
[17:03:01.072] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:01.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:68:0) after 1m0s
[17:04:01.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:68:0)
[17:04:01.188] Timer tick, asking Buildbucket for the build status
[17:04:01.478] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:01.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:69:0) after 1m0s
[17:05:01.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:69:0)
[17:05:01.555] Timer tick, asking Buildbucket for the build status
[17:05:01.664] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:01.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:70:0) after 1m0s
[17:06:01.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:70:0)
[17:06:01.788] Timer tick, asking Buildbucket for the build status
[17:06:01.925] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:01.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:71:0) after 1m0s
[17:07:02.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:71:0)
[17:07:02.203] Timer tick, asking Buildbucket for the build status
[17:07:02.449] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:02.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:72:0) after 1m0s
[17:08:02.693] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:72:0)
[17:08:02.694] Timer tick, asking Buildbucket for the build status
[17:08:02.805] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:02.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:73:0) after 1m0s
[17:09:03.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:73:0)
[17:09:03.002] Timer tick, asking Buildbucket for the build status
[17:09:03.136] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:03.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:74:0) after 1m0s
[17:10:03.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:74:0)
[17:10:03.260] Timer tick, asking Buildbucket for the build status
[17:10:03.419] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:03.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:75:0) after 1m0s
[17:11:03.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:75:0)
[17:11:03.437] Timer tick, asking Buildbucket for the build status
[17:11:03.681] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:03.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:76:0) after 1m0s
[17:12:03.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:76:0)
[17:12:03.843] Timer tick, asking Buildbucket for the build status
[17:12:03.928] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:03.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:77:0) after 1m0s
[17:13:04.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:77:0)
[17:13:04.133] Timer tick, asking Buildbucket for the build status
[17:13:04.242] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:04.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:78:0) after 1m0s
[17:14:04.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:78:0)
[17:14:04.306] Timer tick, asking Buildbucket for the build status
[17:14:04.424] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:04.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:79:0) after 1m0s
[17:15:04.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:79:0)
[17:15:04.667] Timer tick, asking Buildbucket for the build status
[17:15:04.764] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:04.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:80:0) after 1m0s
[17:16:05.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:80:0)
[17:16:05.206] Timer tick, asking Buildbucket for the build status
[17:16:05.405] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:05.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:81:0) after 1m0s
[17:17:05.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:81:0)
[17:17:05.604] Timer tick, asking Buildbucket for the build status
[17:17:05.783] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:05.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:82:0) after 1m0s
[17:18:05.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:82:0)
[17:18:05.836] Timer tick, asking Buildbucket for the build status
[17:18:06.088] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:06.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:83:0) after 1m0s
[17:19:06.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:83:0)
[17:19:06.206] Timer tick, asking Buildbucket for the build status
[17:19:06.335] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:06.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:84:0) after 1m0s
[17:20:06.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:84:0)
[17:20:06.437] Timer tick, asking Buildbucket for the build status
[17:20:06.703] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:06.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:85:0) after 1m0s
[17:21:06.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:85:0)
[17:21:06.716] Timer tick, asking Buildbucket for the build status
[17:21:06.809] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:06.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:86:0) after 1m0s
[17:22:06.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:86:0)
[17:22:06.968] Timer tick, asking Buildbucket for the build status
[17:22:07.082] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:07.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:87:0) after 1m0s
[17:23:07.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:87:0)
[17:23:07.374] Timer tick, asking Buildbucket for the build status
[17:23:07.524] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:07.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:88:0) after 1m0s
[17:24:07.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:88:0)
[17:24:07.675] Timer tick, asking Buildbucket for the build status
[17:24:07.981] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:07.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:89:0) after 1m0s
[17:25:08.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:89:0)
[17:25:08.093] Timer tick, asking Buildbucket for the build status
[17:25:08.308] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:08.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:90:0) after 1m0s
[17:26:08.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:90:0)
[17:26:08.488] Timer tick, asking Buildbucket for the build status
[17:26:08.630] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:08.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:91:0) after 1m0s
[17:27:08.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:91:0)
[17:27:08.712] Timer tick, asking Buildbucket for the build status
[17:27:09.187] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:09.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:92:0) after 1m0s
[17:28:09.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:92:0)
[17:28:09.357] Timer tick, asking Buildbucket for the build status
[17:28:09.477] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:09.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:93:0) after 1m0s
[17:29:09.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:93:0)
[17:29:09.535] Timer tick, asking Buildbucket for the build status
[17:29:09.714] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:09.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:94:0) after 1m0s
[17:30:09.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:94:0)
[17:30:09.754] Timer tick, asking Buildbucket for the build status
[17:30:09.898] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:09.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:95:0) after 1m0s
[17:31:09.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:95:0)
[17:31:09.982] Timer tick, asking Buildbucket for the build status
[17:31:10.271] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:10.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:96:0) after 1m0s
[17:32:10.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:96:0)
[17:32:10.356] Timer tick, asking Buildbucket for the build status
[17:32:10.767] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:10.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:97:0) after 1m0s
[17:33:10.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:97:0)
[17:33:10.900] Timer tick, asking Buildbucket for the build status
[17:33:10.981] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:10.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:98:0) after 1m0s
[17:34:11.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:98:0)
[17:34:11.035] Timer tick, asking Buildbucket for the build status
[17:34:11.206] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:11.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:99:0) after 1m0s
[17:35:11.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:99:0)
[17:35:11.273] Timer tick, asking Buildbucket for the build status
[17:35:11.558] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:11.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:100:0) after 1m0s
[17:36:11.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:100:0)
[17:36:11.741] Timer tick, asking Buildbucket for the build status
[17:36:11.939] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:11.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:101:0) after 1m0s
[17:37:12.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:101:0)
[17:37:12.002] Timer tick, asking Buildbucket for the build status
[17:37:12.362] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:12.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:102:0) after 1m0s
[17:38:12.558] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:102:0)
[17:38:12.558] Timer tick, asking Buildbucket for the build status
[17:38:12.707] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:12.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:103:0) after 1m0s
[17:39:12.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:103:0)
[17:39:12.760] Timer tick, asking Buildbucket for the build status
[17:39:13.354] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:13.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:104:0) after 1m0s
[17:40:13.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:104:0)
[17:40:13.464] Timer tick, asking Buildbucket for the build status
[17:40:13.581] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:13.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:105:0) after 1m0s
[17:41:13.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:105:0)
[17:41:13.605] Timer tick, asking Buildbucket for the build status
[17:41:13.718] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:13.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:106:0) after 1m0s
[17:42:13.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:106:0)
[17:42:13.783] Timer tick, asking Buildbucket for the build status
[17:42:13.877] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:13.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:107:0) after 1m0s
[17:43:13.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:107:0)
[17:43:13.962] Timer tick, asking Buildbucket for the build status
[17:43:14.063] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:14.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:108:0) after 1m0s
[17:44:14.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:108:0)
[17:44:14.091] Timer tick, asking Buildbucket for the build status
[17:44:14.200] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:14.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:109:0) after 1m0s
[17:45:14.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:109:0)
[17:45:14.331] Timer tick, asking Buildbucket for the build status
[17:45:14.588] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:14.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:110:0) after 1m0s
[17:46:14.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:110:0)
[17:46:14.731] Timer tick, asking Buildbucket for the build status
[17:46:14.971] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:14.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:111:0) after 1m0s
[17:47:14.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:111:0)
[17:47:14.983] Timer tick, asking Buildbucket for the build status
[17:47:15.179] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:15.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:112:0) after 1m0s
[17:48:15.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:112:0)
[17:48:15.312] Timer tick, asking Buildbucket for the build status
[17:48:15.435] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:15.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:113:0) after 1m0s
[17:49:15.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:113:0)
[17:49:15.467] Timer tick, asking Buildbucket for the build status
[17:49:15.684] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:15.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:114:0) after 1m0s
[17:50:15.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:114:0)
[17:50:15.730] Timer tick, asking Buildbucket for the build status
[17:50:15.900] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:15.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:115:0) after 1m0s
[17:51:16.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:115:0)
[17:51:16.071] Timer tick, asking Buildbucket for the build status
[17:51:16.183] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:16.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:116:0) after 1m0s
[17:52:16.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:116:0)
[17:52:16.185] Timer tick, asking Buildbucket for the build status
[17:52:16.517] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:16.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:117:0) after 1m0s
[17:53:16.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:117:0)
[17:53:16.562] Timer tick, asking Buildbucket for the build status
[17:53:16.649] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:16.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:118:0) after 1m0s
[17:54:16.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:118:0)
[17:54:16.817] Timer tick, asking Buildbucket for the build status
[17:54:16.949] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:16.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:119:0) after 1m0s
[17:55:17.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:119:0)
[17:55:17.076] Timer tick, asking Buildbucket for the build status
[17:55:17.284] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:17.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:120:0) after 1m0s
[17:56:17.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:120:0)
[17:56:17.361] Timer tick, asking Buildbucket for the build status
[17:56:17.473] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:17.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:121:0) after 1m0s
[17:57:17.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:121:0)
[17:57:17.556] Timer tick, asking Buildbucket for the build status
[17:57:17.714] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:17.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:122:0) after 1m0s
[17:58:17.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:122:0)
[17:58:17.836] Timer tick, asking Buildbucket for the build status
[17:58:17.990] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:17.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:123:0) after 1m0s
[17:59:18.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:123:0)
[17:59:18.064] Timer tick, asking Buildbucket for the build status
[17:59:18.558] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:18.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:124:0) after 1m0s
[18:00:18.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:124:0)
[18:00:18.661] Timer tick, asking Buildbucket for the build status
[18:00:18.772] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:18.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:125:0) after 1m0s
[18:01:18.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:125:0)
[18:01:18.955] Timer tick, asking Buildbucket for the build status
[18:01:19.199] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:19.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:126:0) after 1m0s
[18:02:19.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:126:0)
[18:02:19.382] Timer tick, asking Buildbucket for the build status
[18:02:19.651] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:19.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:127:0) after 1m0s
[18:03:19.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:127:0)
[18:03:19.783] Timer tick, asking Buildbucket for the build status
[18:03:19.879] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:19.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:128:0) after 1m0s
[18:04:19.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:128:0)
[18:04:19.972] Timer tick, asking Buildbucket for the build status
[18:04:20.142] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:20.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:129:0) after 1m0s
[18:05:20.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:129:0)
[18:05:20.185] Timer tick, asking Buildbucket for the build status
[18:05:20.573] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:20.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:130:0) after 1m0s
[18:06:20.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:130:0)
[18:06:20.609] Timer tick, asking Buildbucket for the build status
[18:06:21.069] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:21.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:131:0) after 1m0s
[18:07:21.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:131:0)
[18:07:21.184] Timer tick, asking Buildbucket for the build status
[18:07:21.356] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:21.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:132:0) after 1m0s
[18:08:21.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:132:0)
[18:08:21.378] Timer tick, asking Buildbucket for the build status
[18:08:21.508] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:21.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:133:0) after 1m0s
[18:09:21.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:133:0)
[18:09:21.678] Timer tick, asking Buildbucket for the build status
[18:09:21.768] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:21.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:134:0) after 1m0s
[18:10:21.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:134:0)
[18:10:21.827] Timer tick, asking Buildbucket for the build status
[18:10:22.087] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:22.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:135:0) after 1m0s
[18:11:22.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:135:0)
[18:11:22.174] Timer tick, asking Buildbucket for the build status
[18:11:22.671] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:22.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:136:0) after 1m0s
[18:12:22.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:136:0)
[18:12:22.828] Timer tick, asking Buildbucket for the build status
[18:12:22.971] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:22.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:137:0) after 1m0s
[18:13:23.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:137:0)
[18:13:23.105] Timer tick, asking Buildbucket for the build status
[18:13:23.483] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:23.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:138:0) after 1m0s
[18:14:23.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:138:0)
[18:14:23.635] Timer tick, asking Buildbucket for the build status
[18:14:23.742] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:23.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:139:0) after 1m0s
[18:15:23.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:139:0)
[18:15:23.791] Timer tick, asking Buildbucket for the build status
[18:15:24.223] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:24.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:140:0) after 1m0s
[18:16:24.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:140:0)
[18:16:24.390] Timer tick, asking Buildbucket for the build status
[18:16:24.578] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:24.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:141:0) after 1m0s
[18:17:24.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:141:0)
[18:17:24.699] Timer tick, asking Buildbucket for the build status
[18:17:24.803] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:24.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:142:0) after 1m0s
[18:18:24.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:142:0)
[18:18:24.926] Timer tick, asking Buildbucket for the build status
[18:18:25.104] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:25.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:143:0) after 1m0s
[18:19:25.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:143:0)
[18:19:25.432] Timer tick, asking Buildbucket for the build status
[18:19:25.676] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:25.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:144:0) after 1m0s
[18:20:25.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:144:0)
[18:20:25.770] Timer tick, asking Buildbucket for the build status
[18:20:26.203] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:26.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:145:0) after 1m0s
[18:21:26.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:145:0)
[18:21:26.385] Timer tick, asking Buildbucket for the build status
[18:21:26.538] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:26.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:146:0) after 1m0s
[18:22:26.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:146:0)
[18:22:26.621] Timer tick, asking Buildbucket for the build status
[18:22:26.787] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:26.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:147:0) after 1m0s
[18:23:26.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:147:0)
[18:23:26.852] Timer tick, asking Buildbucket for the build status
[18:23:27.107] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:27.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:148:0) after 1m0s
[18:24:27.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:148:0)
[18:24:27.168] Timer tick, asking Buildbucket for the build status
[18:24:27.284] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:27.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:149:0) after 1m0s
[18:25:27.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:149:0)
[18:25:27.347] Timer tick, asking Buildbucket for the build status
[18:25:27.464] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:27.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:150:0) after 1m0s
[18:26:27.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:150:0)
[18:26:27.626] Timer tick, asking Buildbucket for the build status
[18:26:27.752] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:27.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:151:0) after 1m0s
[18:27:27.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:151:0)
[18:27:27.809] Timer tick, asking Buildbucket for the build status
[18:27:28.013] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:28.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:152:0) after 1m0s
[18:28:28.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:152:0)
[18:28:28.206] Timer tick, asking Buildbucket for the build status
[18:28:28.405] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:28.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:153:0) after 1m0s
[18:29:28.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:153:0)
[18:29:28.514] Timer tick, asking Buildbucket for the build status
[18:29:28.648] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:28.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:154:0) after 1m0s
[18:30:28.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:154:0)
[18:30:28.795] Timer tick, asking Buildbucket for the build status
[18:30:29.102] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:29.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:155:0) after 1m0s
[18:31:29.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:155:0)
[18:31:29.232] Timer tick, asking Buildbucket for the build status
[18:31:29.344] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:29.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:156:0) after 1m0s
[18:32:29.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:156:0)
[18:32:29.538] Timer tick, asking Buildbucket for the build status
[18:32:29.632] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:29.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:157:0) after 1m0s
[18:33:30.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:157:0)
[18:33:30.159] Timer tick, asking Buildbucket for the build status
[18:33:30.285] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:30.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:158:0) after 1m0s
[18:34:30.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:158:0)
[18:34:30.427] Timer tick, asking Buildbucket for the build status
[18:34:30.529] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:30.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:159:0) after 1m0s
[18:35:30.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:159:0)
[18:35:30.788] Timer tick, asking Buildbucket for the build status
[18:35:31.141] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:31.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:160:0) after 1m0s
[18:36:31.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:160:0)
[18:36:31.244] Timer tick, asking Buildbucket for the build status
[18:36:31.323] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:31.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:161:0) after 1m0s
[18:37:32.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:161:0)
[18:37:32.030] Timer tick, asking Buildbucket for the build status
[18:37:32.142] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:32.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:162:0) after 1m0s
[18:38:32.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:162:0)
[18:38:32.760] Timer tick, asking Buildbucket for the build status
[18:38:33.076] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:33.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:163:0) after 1m0s
[18:39:33.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:163:0)
[18:39:33.133] Timer tick, asking Buildbucket for the build status
[18:39:33.225] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:33.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:164:0) after 1m0s
[18:40:33.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:164:0)
[18:40:33.280] Timer tick, asking Buildbucket for the build status
[18:40:33.467] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:33.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:165:0) after 1m0s
[18:41:33.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:165:0)
[18:41:33.622] Timer tick, asking Buildbucket for the build status
[18:41:33.769] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:33.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:166:0) after 1m0s
[18:42:34.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:166:0)
[18:42:34.255] Timer tick, asking Buildbucket for the build status
[18:42:34.387] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:34.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:167:0) after 1m0s
[18:43:34.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:167:0)
[18:43:34.605] Timer tick, asking Buildbucket for the build status
[18:43:34.738] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:34.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:168:0) after 1m0s
[18:44:34.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:168:0)
[18:44:34.829] Timer tick, asking Buildbucket for the build status
[18:44:35.023] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:35.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:169:0) after 1m0s
[18:45:35.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:169:0)
[18:45:35.216] Timer tick, asking Buildbucket for the build status
[18:45:35.311] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:35.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:170:0) after 1m0s
[18:46:35.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:170:0)
[18:46:35.679] Timer tick, asking Buildbucket for the build status
[18:46:36.341] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:36.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:171:0) after 1m0s
[18:47:36.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:171:0)
[18:47:36.418] Timer tick, asking Buildbucket for the build status
[18:47:36.698] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:36.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:172:0) after 1m0s
[18:48:36.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:172:0)
[18:48:36.817] Timer tick, asking Buildbucket for the build status
[18:48:37.148] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:37.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:173:0) after 1m0s
[18:49:37.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:173:0)
[18:49:37.234] Timer tick, asking Buildbucket for the build status
[18:49:37.508] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:37.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:174:0) after 1m0s
[18:50:37.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:174:0)
[18:50:37.606] Timer tick, asking Buildbucket for the build status
[18:50:38.376] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:38.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:175:0) after 1m0s
[18:51:38.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:175:0)
[18:51:38.525] Timer tick, asking Buildbucket for the build status
[18:51:38.783] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:38.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:176:0) after 1m0s
[18:52:38.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:176:0)
[18:52:38.836] Timer tick, asking Buildbucket for the build status
[18:52:39.203] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:39.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:177:0) after 1m0s
[18:53:39.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:177:0)
[18:53:39.220] Timer tick, asking Buildbucket for the build status
[18:53:39.379] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:39.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:178:0) after 1m0s
[18:54:39.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:178:0)
[18:54:39.443] Timer tick, asking Buildbucket for the build status
[18:54:39.579] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:39.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:179:0) after 1m0s
[18:55:39.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:179:0)
[18:55:39.682] Timer tick, asking Buildbucket for the build status
[18:55:40.175] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:40.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:180:0) after 1m0s
[18:56:40.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:180:0)
[18:56:40.217] Timer tick, asking Buildbucket for the build status
[18:56:40.366] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:40.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:181:0) after 1m0s
[18:57:40.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:181:0)
[18:57:40.493] Timer tick, asking Buildbucket for the build status
[18:57:40.613] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:40.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:182:0) after 1m0s
[18:58:40.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:182:0)
[18:58:40.685] Timer tick, asking Buildbucket for the build status
[18:58:40.807] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:40.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:183:0) after 1m0s
[18:59:40.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:183:0)
[18:59:40.859] Timer tick, asking Buildbucket for the build status
[18:59:40.991] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:40.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:184:0) after 1m0s
[19:00:41.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:184:0)
[19:00:41.089] Timer tick, asking Buildbucket for the build status
[19:00:41.218] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:41.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:185:0) after 1m0s
[19:01:41.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:185:0)
[19:01:41.268] Timer tick, asking Buildbucket for the build status
[19:01:41.493] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:41.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:186:0) after 1m0s
[19:02:41.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:186:0)
[19:02:41.527] Timer tick, asking Buildbucket for the build status
[19:02:41.627] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:41.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:187:0) after 1m0s
[19:03:41.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:187:0)
[19:03:41.773] Timer tick, asking Buildbucket for the build status
[19:03:41.869] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:41.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:188:0) after 1m0s
[19:04:41.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:188:0)
[19:04:41.867] Timer tick, asking Buildbucket for the build status
[19:04:41.959] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:41.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:189:0) after 1m0s
[19:05:42.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:189:0)
[19:05:42.089] Timer tick, asking Buildbucket for the build status
[19:05:42.269] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:42.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:190:0) after 1m0s
[19:06:42.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:190:0)
[19:06:42.383] Timer tick, asking Buildbucket for the build status
[19:06:42.796] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:42.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:191:0) after 1m0s
[19:07:42.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:191:0)
[19:07:42.872] Timer tick, asking Buildbucket for the build status
[19:07:42.965] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:42.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:192:0) after 1m0s
[19:08:42.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:192:0)
[19:08:42.995] Timer tick, asking Buildbucket for the build status
[19:08:43.106] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:43.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:193:0) after 1m0s
[19:09:43.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:193:0)
[19:09:43.176] Timer tick, asking Buildbucket for the build status
[19:09:43.355] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:43.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:194:0) after 1m0s
[19:10:43.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:194:0)
[19:10:43.367] Timer tick, asking Buildbucket for the build status
[19:10:43.672] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:43.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:195:0) after 1m0s
[19:11:43.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:195:0)
[19:11:43.733] Timer tick, asking Buildbucket for the build status
[19:11:43.976] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:43.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:196:0) after 1m0s
[19:12:44.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:196:0)
[19:12:44.038] Timer tick, asking Buildbucket for the build status
[19:12:44.387] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:44.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:197:0) after 1m0s
[19:13:44.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:197:0)
[19:13:44.472] Timer tick, asking Buildbucket for the build status
[19:13:44.568] Build 8886653457293968832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:44.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9052083520292641280:198:0) after 1m0s
[19:14:42.111] Received PubSub notification, asking Buildbucket for the build status
[19:14:42.438] Build 8886653457293968832: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:14:42.438] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1583435681601797",
    "created_by": "project:nacl",
    "created_ts": "1583423861183555",
    "failure_reason": "BUILD_FAILURE",
    "id": "8886653457293968832",
    "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\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"title\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}}",
    "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\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"title\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-rdnq\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5088, \"got_revision\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"87b7f311d3a006f29e28a3631cc19f9f30577ac3\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-rdnq\"], \"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": "1583423894595981",
    "status": "COMPLETED",
    "status_changed_ts": "1583423895125180",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5088",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/87b7f311d3a006f29e28a3631cc19f9f30577ac3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052083520292641280",
      "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/8886653457293968832/+/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:4ac43c3e90feff10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1583435681683852",
    "url": "https://ci.chromium.org/b/8886653457293968832",
    "utcnow_ts": "1583435682409095"
  }
}
[19:14:42.438] Invocation finished in 3h17m3.4981407s with status FAILED