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

• 8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1 (4 years ago)

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

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

[01:19:18.247] New invocation is queued and will start shortly
[01:19:19.315] Starting the invocation (attempt 1)
[01:19:19.350] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:19:19.351] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:19:19.351] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9020882827593163520",
  "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\":\"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\",\"title\":\"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}}",
  "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:9020882827593163520",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
    "gitiles_ref:refs/heads/master"
  ]
}
[01:19:20.248] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1613179159428655",
    "id": "8855452765681471952",
    "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\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"title\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1613179159951231",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5129",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9020882827593163520",
      "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/8855452765681471952/+/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": "1613179159952664",
    "url": "https://ci.chromium.org/b/8855452765681471952",
    "utcnow_ts": "1613179160227148"
  }
}
[01:19:20.248] Task URL: https://ci.chromium.org/b/8855452765681471952
[01:19:20.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:2:0) after 1m0s
[01:20:20.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:2:0)
[01:20:20.305] Timer tick, asking Buildbucket for the build status
[01:20:20.475] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:20.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:3:0) after 1m0s
[01:21:20.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:3:0)
[01:21:20.543] Timer tick, asking Buildbucket for the build status
[01:21:20.716] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:20.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:4:0) after 1m0s
[01:22:20.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:4:0)
[01:22:20.713] Timer tick, asking Buildbucket for the build status
[01:22:20.785] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:20.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:5:0) after 1m0s
[01:23:21.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:5:0)
[01:23:21.011] Timer tick, asking Buildbucket for the build status
[01:23:21.091] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:21.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:6:0) after 1m0s
[01:24:21.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:6:0)
[01:24:21.157] Timer tick, asking Buildbucket for the build status
[01:24:21.226] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:21.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:7:0) after 1m0s
[01:25:21.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:7:0)
[01:25:21.292] Timer tick, asking Buildbucket for the build status
[01:25:21.484] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:21.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:8:0) after 1m0s
[01:26:21.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:8:0)
[01:26:21.653] Timer tick, asking Buildbucket for the build status
[01:26:21.952] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:21.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:9:0) after 1m0s
[01:27:22.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:9:0)
[01:27:22.073] Timer tick, asking Buildbucket for the build status
[01:27:22.210] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:22.210] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:10:0) after 1m0s
[01:28:22.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:10:0)
[01:28:22.347] Timer tick, asking Buildbucket for the build status
[01:28:22.420] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:22.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:11:0) after 1m0s
[01:29:22.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:11:0)
[01:29:22.478] Timer tick, asking Buildbucket for the build status
[01:29:22.581] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:22.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:12:0) after 1m0s
[01:30:22.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:12:0)
[01:30:22.653] Timer tick, asking Buildbucket for the build status
[01:30:22.945] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:22.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:13:0) after 1m0s
[01:31:23.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:13:0)
[01:31:23.053] Timer tick, asking Buildbucket for the build status
[01:31:23.181] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:23.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:14:0) after 1m0s
[01:32:23.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:14:0)
[01:32:23.223] Timer tick, asking Buildbucket for the build status
[01:32:23.295] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:23.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:15:0) after 1m0s
[01:33:23.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:15:0)
[01:33:23.356] Timer tick, asking Buildbucket for the build status
[01:33:23.457] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:23.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:16:0) after 1m0s
[01:34:23.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:16:0)
[01:34:23.500] Timer tick, asking Buildbucket for the build status
[01:34:23.572] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:23.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:17:0) after 1m0s
[01:35:23.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:17:0)
[01:35:23.654] Timer tick, asking Buildbucket for the build status
[01:35:23.787] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:23.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:18:0) after 1m0s
[01:36:23.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:18:0)
[01:36:23.906] Timer tick, asking Buildbucket for the build status
[01:36:24.034] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:24.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:19:0) after 1m0s
[01:37:24.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:19:0)
[01:37:24.065] Timer tick, asking Buildbucket for the build status
[01:37:24.219] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:24.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:20:0) after 1m0s
[01:38:24.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:20:0)
[01:38:24.293] Timer tick, asking Buildbucket for the build status
[01:38:24.438] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:24.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:21:0) after 1m0s
[01:39:24.571] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:21:0)
[01:39:24.571] Timer tick, asking Buildbucket for the build status
[01:39:24.770] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:24.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:22:0) after 1m0s
[01:40:24.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:22:0)
[01:40:24.815] Timer tick, asking Buildbucket for the build status
[01:40:24.928] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:24.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:23:0) after 1m0s
[01:41:24.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:23:0)
[01:41:24.927] Timer tick, asking Buildbucket for the build status
[01:41:25.120] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:25.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:24:0) after 1m0s
[01:42:25.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:24:0)
[01:42:25.130] Timer tick, asking Buildbucket for the build status
[01:42:25.212] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:25.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:25:0) after 1m0s
[01:43:25.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:25:0)
[01:43:25.271] Timer tick, asking Buildbucket for the build status
[01:43:25.351] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:25.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:26:0) after 1m0s
[01:44:25.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:26:0)
[01:44:25.435] Timer tick, asking Buildbucket for the build status
[01:44:25.552] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:25.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:27:0) after 1m0s
[01:45:25.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:27:0)
[01:45:25.599] Timer tick, asking Buildbucket for the build status
[01:45:25.696] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:25.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:28:0) after 1m0s
[01:46:25.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:28:0)
[01:46:25.736] Timer tick, asking Buildbucket for the build status
[01:46:25.819] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:25.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:29:0) after 1m0s
[01:47:26.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:29:0)
[01:47:26.012] Timer tick, asking Buildbucket for the build status
[01:47:26.118] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:26.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:30:0) after 1m0s
[01:48:26.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:30:0)
[01:48:26.145] Timer tick, asking Buildbucket for the build status
[01:48:26.336] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:26.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:31:0) after 1m0s
[01:49:26.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:31:0)
[01:49:26.420] Timer tick, asking Buildbucket for the build status
[01:49:26.549] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:26.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:32:0) after 1m0s
[01:50:26.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:32:0)
[01:50:26.685] Timer tick, asking Buildbucket for the build status
[01:50:26.803] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:26.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:33:0) after 1m0s
[01:51:26.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:33:0)
[01:51:26.913] Timer tick, asking Buildbucket for the build status
[01:51:26.984] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:26.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:34:0) after 1m0s
[01:52:27.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:34:0)
[01:52:27.024] Timer tick, asking Buildbucket for the build status
[01:52:27.166] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:27.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:35:0) after 1m0s
[01:53:27.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:35:0)
[01:53:27.279] Timer tick, asking Buildbucket for the build status
[01:53:27.356] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:27.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:36:0) after 1m0s
[01:54:27.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:36:0)
[01:54:27.352] Timer tick, asking Buildbucket for the build status
[01:54:27.520] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:27.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:37:0) after 1m0s
[01:55:27.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:37:0)
[01:55:27.547] Timer tick, asking Buildbucket for the build status
[01:55:27.653] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:27.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:38:0) after 1m0s
[01:56:27.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:38:0)
[01:56:27.831] Timer tick, asking Buildbucket for the build status
[01:56:27.925] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:27.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:39:0) after 1m0s
[01:57:27.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:39:0)
[01:57:27.963] Timer tick, asking Buildbucket for the build status
[01:57:28.065] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:28.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:40:0) after 1m0s
[01:58:28.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:40:0)
[01:58:28.138] Timer tick, asking Buildbucket for the build status
[01:58:28.300] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:28.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:41:0) after 1m0s
[01:59:28.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:41:0)
[01:59:28.393] Timer tick, asking Buildbucket for the build status
[01:59:28.765] Build 8855452765681471952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:28.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:42:0) after 1m0s
[02:00:02.006] Received PubSub notification, asking Buildbucket for the build status
[02:00:02.108] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:28.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:42:0)
[02:00:28.855] Timer tick, asking Buildbucket for the build status
[02:00:28.958] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:28.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:44:0) after 1m0s
[02:01:29.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:44:0)
[02:01:29.062] Timer tick, asking Buildbucket for the build status
[02:01:29.145] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:29.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:45:0) after 1m0s
[02:02:29.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:45:0)
[02:02:29.217] Timer tick, asking Buildbucket for the build status
[02:02:29.296] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:29.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:46:0) after 1m0s
[02:03:29.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:46:0)
[02:03:29.328] Timer tick, asking Buildbucket for the build status
[02:03:29.732] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:29.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:47:0) after 1m0s
[02:04:29.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:47:0)
[02:04:29.766] Timer tick, asking Buildbucket for the build status
[02:04:29.858] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:29.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:48:0) after 1m0s
[02:05:29.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:48:0)
[02:05:29.850] Timer tick, asking Buildbucket for the build status
[02:05:29.960] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:29.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:49:0) after 1m0s
[02:06:30.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:49:0)
[02:06:30.008] Timer tick, asking Buildbucket for the build status
[02:06:30.090] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:30.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:50:0) after 1m0s
[02:07:30.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:50:0)
[02:07:30.160] Timer tick, asking Buildbucket for the build status
[02:07:30.253] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:30.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:51:0) after 1m0s
[02:08:30.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:51:0)
[02:08:30.231] Timer tick, asking Buildbucket for the build status
[02:08:30.315] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:30.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:52:0) after 1m0s
[02:09:30.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:52:0)
[02:09:30.373] Timer tick, asking Buildbucket for the build status
[02:09:30.735] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:30.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:53:0) after 1m0s
[02:10:30.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:53:0)
[02:10:30.882] Timer tick, asking Buildbucket for the build status
[02:10:31.021] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:31.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:54:0) after 1m0s
[02:11:31.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:54:0)
[02:11:31.081] Timer tick, asking Buildbucket for the build status
[02:11:31.183] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:31.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:55:0) after 1m0s
[02:12:31.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:55:0)
[02:12:31.193] Timer tick, asking Buildbucket for the build status
[02:12:31.308] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:31.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:56:0) after 1m0s
[02:13:31.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:56:0)
[02:13:31.304] Timer tick, asking Buildbucket for the build status
[02:13:31.474] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:31.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:57:0) after 1m0s
[02:14:31.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:57:0)
[02:14:31.553] Timer tick, asking Buildbucket for the build status
[02:14:31.675] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:31.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:58:0) after 1m0s
[02:15:31.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:58:0)
[02:15:31.708] Timer tick, asking Buildbucket for the build status
[02:15:31.830] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:31.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:59:0) after 1m0s
[02:16:31.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:59:0)
[02:16:31.836] Timer tick, asking Buildbucket for the build status
[02:16:31.963] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:31.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:60:0) after 1m0s
[02:17:31.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:60:0)
[02:17:31.989] Timer tick, asking Buildbucket for the build status
[02:17:32.466] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:32.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:61:0) after 1m0s
[02:18:32.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:61:0)
[02:18:32.497] Timer tick, asking Buildbucket for the build status
[02:18:32.585] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:32.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:62:0) after 1m0s
[02:19:32.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:62:0)
[02:19:32.697] Timer tick, asking Buildbucket for the build status
[02:19:32.922] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:32.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:63:0) after 1m0s
[02:20:32.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:63:0)
[02:20:32.932] Timer tick, asking Buildbucket for the build status
[02:20:33.040] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:33.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:64:0) after 1m0s
[02:21:33.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:64:0)
[02:21:33.059] Timer tick, asking Buildbucket for the build status
[02:21:33.172] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:33.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:65:0) after 1m0s
[02:22:33.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:65:0)
[02:22:33.208] Timer tick, asking Buildbucket for the build status
[02:22:33.316] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:33.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:66:0) after 1m0s
[02:23:33.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:66:0)
[02:23:33.361] Timer tick, asking Buildbucket for the build status
[02:23:33.457] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:33.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:67:0) after 1m0s
[02:24:33.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:67:0)
[02:24:33.503] Timer tick, asking Buildbucket for the build status
[02:24:33.596] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:33.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:68:0) after 1m0s
[02:25:33.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:68:0)
[02:25:33.663] Timer tick, asking Buildbucket for the build status
[02:25:33.837] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:33.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:69:0) after 1m0s
[02:26:33.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:69:0)
[02:26:33.894] Timer tick, asking Buildbucket for the build status
[02:26:33.969] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:33.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:70:0) after 1m0s
[02:27:33.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:70:0)
[02:27:33.970] Timer tick, asking Buildbucket for the build status
[02:27:34.068] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:34.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:71:0) after 1m0s
[02:28:34.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:71:0)
[02:28:34.160] Timer tick, asking Buildbucket for the build status
[02:28:34.244] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:34.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:72:0) after 1m0s
[02:29:34.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:72:0)
[02:29:34.260] Timer tick, asking Buildbucket for the build status
[02:29:34.366] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:34.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:73:0) after 1m0s
[02:30:34.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:73:0)
[02:30:34.867] Timer tick, asking Buildbucket for the build status
[02:30:34.943] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:34.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:74:0) after 1m0s
[02:31:35.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:74:0)
[02:31:35.004] Timer tick, asking Buildbucket for the build status
[02:31:35.216] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:35.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:75:0) after 1m0s
[02:32:35.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:75:0)
[02:32:35.394] Timer tick, asking Buildbucket for the build status
[02:32:35.523] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:35.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:76:0) after 1m0s
[02:33:35.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:76:0)
[02:33:35.647] Timer tick, asking Buildbucket for the build status
[02:33:35.749] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:35.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:77:0) after 1m0s
[02:34:36.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:77:0)
[02:34:36.000] Timer tick, asking Buildbucket for the build status
[02:34:36.131] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:36.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:78:0) after 1m0s
[02:35:36.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:78:0)
[02:35:36.248] Timer tick, asking Buildbucket for the build status
[02:35:36.459] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:36.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:79:0) after 1m0s
[02:36:36.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:79:0)
[02:36:36.492] Timer tick, asking Buildbucket for the build status
[02:36:36.598] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:36.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:80:0) after 1m0s
[02:37:36.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:80:0)
[02:37:36.688] Timer tick, asking Buildbucket for the build status
[02:37:36.772] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:36.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:81:0) after 1m0s
[02:38:36.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:81:0)
[02:38:36.835] Timer tick, asking Buildbucket for the build status
[02:38:36.940] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:36.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:82:0) after 1m0s
[02:39:37.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:82:0)
[02:39:37.074] Timer tick, asking Buildbucket for the build status
[02:39:37.511] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:37.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:83:0) after 1m0s
[02:40:37.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:83:0)
[02:40:37.704] Timer tick, asking Buildbucket for the build status
[02:40:37.831] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:37.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:84:0) after 1m0s
[02:41:37.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:84:0)
[02:41:37.996] Timer tick, asking Buildbucket for the build status
[02:41:38.068] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:38.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:85:0) after 1m0s
[02:42:38.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:85:0)
[02:42:38.154] Timer tick, asking Buildbucket for the build status
[02:42:38.448] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:38.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:86:0) after 1m0s
[02:43:38.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:86:0)
[02:43:38.492] Timer tick, asking Buildbucket for the build status
[02:43:38.573] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:38.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:87:0) after 1m0s
[02:44:38.629] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:87:0)
[02:44:38.629] Timer tick, asking Buildbucket for the build status
[02:44:38.768] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:38.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:88:0) after 1m0s
[02:45:38.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:88:0)
[02:45:38.834] Timer tick, asking Buildbucket for the build status
[02:45:39.119] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:39.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:89:0) after 1m0s
[02:46:39.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:89:0)
[02:46:39.164] Timer tick, asking Buildbucket for the build status
[02:46:39.250] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:39.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:90:0) after 1m0s
[02:47:39.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:90:0)
[02:47:39.299] Timer tick, asking Buildbucket for the build status
[02:47:39.389] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:39.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:91:0) after 1m0s
[02:48:39.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:91:0)
[02:48:39.443] Timer tick, asking Buildbucket for the build status
[02:48:39.510] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:39.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:92:0) after 1m0s
[02:49:39.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:92:0)
[02:49:39.529] Timer tick, asking Buildbucket for the build status
[02:49:39.634] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:39.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:93:0) after 1m0s
[02:50:39.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:93:0)
[02:50:39.659] Timer tick, asking Buildbucket for the build status
[02:50:39.826] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:39.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:94:0) after 1m0s
[02:51:39.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:94:0)
[02:51:39.877] Timer tick, asking Buildbucket for the build status
[02:51:40.131] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:40.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:95:0) after 1m0s
[02:52:40.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:95:0)
[02:52:40.180] Timer tick, asking Buildbucket for the build status
[02:52:40.252] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:40.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:96:0) after 1m0s
[02:53:40.260] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:96:0)
[02:53:40.260] Timer tick, asking Buildbucket for the build status
[02:53:40.326] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:40.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:97:0) after 1m0s
[02:54:40.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:97:0)
[02:54:40.432] Timer tick, asking Buildbucket for the build status
[02:54:40.541] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:40.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:98:0) after 1m0s
[02:55:40.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:98:0)
[02:55:40.593] Timer tick, asking Buildbucket for the build status
[02:55:40.832] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:40.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:99:0) after 1m0s
[02:56:40.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:99:0)
[02:56:40.852] Timer tick, asking Buildbucket for the build status
[02:56:41.030] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:41.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:100:0) after 1m0s
[02:57:41.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:100:0)
[02:57:41.039] Timer tick, asking Buildbucket for the build status
[02:57:41.134] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:41.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:101:0) after 1m0s
[02:58:41.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:101:0)
[02:58:41.206] Timer tick, asking Buildbucket for the build status
[02:58:41.296] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:41.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:102:0) after 1m0s
[02:59:41.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:102:0)
[02:59:41.397] Timer tick, asking Buildbucket for the build status
[02:59:41.503] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:41.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:103:0) after 1m0s
[03:00:41.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:103:0)
[03:00:41.620] Timer tick, asking Buildbucket for the build status
[03:00:41.747] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:41.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:104:0) after 1m0s
[03:01:41.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:104:0)
[03:01:41.757] Timer tick, asking Buildbucket for the build status
[03:01:41.905] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:41.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:105:0) after 1m0s
[03:02:42.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:105:0)
[03:02:42.035] Timer tick, asking Buildbucket for the build status
[03:02:42.141] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:42.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:106:0) after 1m0s
[03:03:42.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:106:0)
[03:03:42.161] Timer tick, asking Buildbucket for the build status
[03:03:42.273] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:42.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:107:0) after 1m0s
[03:04:42.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:107:0)
[03:04:42.383] Timer tick, asking Buildbucket for the build status
[03:04:42.596] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:42.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:108:0) after 1m0s
[03:05:42.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:108:0)
[03:05:42.623] Timer tick, asking Buildbucket for the build status
[03:05:43.100] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:43.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:109:0) after 1m0s
[03:06:43.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:109:0)
[03:06:43.147] Timer tick, asking Buildbucket for the build status
[03:06:43.235] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:43.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:110:0) after 1m0s
[03:07:43.315] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:110:0)
[03:07:43.315] Timer tick, asking Buildbucket for the build status
[03:07:43.427] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:43.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:111:0) after 1m0s
[03:08:43.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:111:0)
[03:08:43.487] Timer tick, asking Buildbucket for the build status
[03:08:43.779] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:43.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:112:0) after 1m0s
[03:09:43.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:112:0)
[03:09:43.813] Timer tick, asking Buildbucket for the build status
[03:09:43.938] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:43.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:113:0) after 1m0s
[03:10:44.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:113:0)
[03:10:44.016] Timer tick, asking Buildbucket for the build status
[03:10:44.334] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:44.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:114:0) after 1m0s
[03:11:44.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:114:0)
[03:11:44.385] Timer tick, asking Buildbucket for the build status
[03:11:44.474] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:44.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:115:0) after 1m0s
[03:12:44.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:115:0)
[03:12:44.510] Timer tick, asking Buildbucket for the build status
[03:12:44.590] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:44.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:116:0) after 1m0s
[03:13:44.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:116:0)
[03:13:44.658] Timer tick, asking Buildbucket for the build status
[03:13:44.848] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:44.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:117:0) after 1m0s
[03:14:45.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:117:0)
[03:14:45.113] Timer tick, asking Buildbucket for the build status
[03:14:45.550] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:45.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:118:0) after 1m0s
[03:15:45.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:118:0)
[03:15:45.597] Timer tick, asking Buildbucket for the build status
[03:15:45.688] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:45.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:119:0) after 1m0s
[03:16:45.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:119:0)
[03:16:45.758] Timer tick, asking Buildbucket for the build status
[03:16:45.845] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:45.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:120:0) after 1m0s
[03:17:45.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:120:0)
[03:17:45.925] Timer tick, asking Buildbucket for the build status
[03:17:46.024] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:46.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:121:0) after 1m0s
[03:18:46.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:121:0)
[03:18:46.079] Timer tick, asking Buildbucket for the build status
[03:18:46.254] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:46.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:122:0) after 1m0s
[03:19:46.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:122:0)
[03:19:46.266] Timer tick, asking Buildbucket for the build status
[03:19:46.365] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:46.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:123:0) after 1m0s
[03:20:46.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:123:0)
[03:20:46.398] Timer tick, asking Buildbucket for the build status
[03:20:46.480] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:46.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:124:0) after 1m0s
[03:21:46.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:124:0)
[03:21:46.505] Timer tick, asking Buildbucket for the build status
[03:21:46.598] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:46.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:125:0) after 1m0s
[03:22:46.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:125:0)
[03:22:46.627] Timer tick, asking Buildbucket for the build status
[03:22:46.740] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:46.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:126:0) after 1m0s
[03:23:46.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:126:0)
[03:23:46.712] Timer tick, asking Buildbucket for the build status
[03:23:46.886] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:46.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:127:0) after 1m0s
[03:24:46.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:127:0)
[03:24:46.923] Timer tick, asking Buildbucket for the build status
[03:24:47.026] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:47.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:128:0) after 1m0s
[03:25:47.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:128:0)
[03:25:47.042] Timer tick, asking Buildbucket for the build status
[03:25:47.120] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:47.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:129:0) after 1m0s
[03:26:47.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:129:0)
[03:26:47.248] Timer tick, asking Buildbucket for the build status
[03:26:47.343] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:47.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:130:0) after 1m0s
[03:27:47.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:130:0)
[03:27:47.396] Timer tick, asking Buildbucket for the build status
[03:27:47.699] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:47.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:131:0) after 1m0s
[03:28:47.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:131:0)
[03:28:47.790] Timer tick, asking Buildbucket for the build status
[03:28:47.864] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:47.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:132:0) after 1m0s
[03:29:47.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:132:0)
[03:29:47.885] Timer tick, asking Buildbucket for the build status
[03:29:47.988] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:47.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:133:0) after 1m0s
[03:30:48.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:133:0)
[03:30:48.047] Timer tick, asking Buildbucket for the build status
[03:30:48.307] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:48.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:134:0) after 1m0s
[03:31:48.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:134:0)
[03:31:48.408] Timer tick, asking Buildbucket for the build status
[03:31:48.495] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:48.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:135:0) after 1m0s
[03:32:48.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:135:0)
[03:32:48.556] Timer tick, asking Buildbucket for the build status
[03:32:48.637] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:48.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:136:0) after 1m0s
[03:33:48.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:136:0)
[03:33:48.694] Timer tick, asking Buildbucket for the build status
[03:33:48.804] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:48.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:137:0) after 1m0s
[03:34:48.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:137:0)
[03:34:48.844] Timer tick, asking Buildbucket for the build status
[03:34:49.409] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:49.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:138:0) after 1m0s
[03:35:49.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:138:0)
[03:35:49.457] Timer tick, asking Buildbucket for the build status
[03:35:49.545] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:49.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:139:0) after 1m0s
[03:36:49.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:139:0)
[03:36:49.574] Timer tick, asking Buildbucket for the build status
[03:36:49.647] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:49.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:140:0) after 1m0s
[03:37:49.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:140:0)
[03:37:49.758] Timer tick, asking Buildbucket for the build status
[03:37:50.025] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:50.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:141:0) after 1m0s
[03:38:50.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:141:0)
[03:38:50.087] Timer tick, asking Buildbucket for the build status
[03:38:50.404] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:50.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:142:0) after 1m0s
[03:39:50.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:142:0)
[03:39:50.431] Timer tick, asking Buildbucket for the build status
[03:39:50.750] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:50.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:143:0) after 1m0s
[03:40:50.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:143:0)
[03:40:50.757] Timer tick, asking Buildbucket for the build status
[03:40:51.030] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:51.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:144:0) after 1m0s
[03:41:51.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:144:0)
[03:41:51.076] Timer tick, asking Buildbucket for the build status
[03:41:51.152] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:51.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:145:0) after 1m0s
[03:42:51.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:145:0)
[03:42:51.198] Timer tick, asking Buildbucket for the build status
[03:42:51.398] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:51.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:146:0) after 1m0s
[03:43:51.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:146:0)
[03:43:51.451] Timer tick, asking Buildbucket for the build status
[03:43:51.637] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:51.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:147:0) after 1m0s
[03:44:51.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:147:0)
[03:44:51.706] Timer tick, asking Buildbucket for the build status
[03:44:51.916] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:51.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:148:0) after 1m0s
[03:45:51.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:148:0)
[03:45:51.964] Timer tick, asking Buildbucket for the build status
[03:45:52.186] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:52.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:149:0) after 1m0s
[03:46:52.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:149:0)
[03:46:52.330] Timer tick, asking Buildbucket for the build status
[03:46:52.417] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:52.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:150:0) after 1m0s
[03:47:52.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:150:0)
[03:47:52.439] Timer tick, asking Buildbucket for the build status
[03:47:52.517] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:52.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:151:0) after 1m0s
[03:48:52.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:151:0)
[03:48:52.588] Timer tick, asking Buildbucket for the build status
[03:48:52.678] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:52.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:152:0) after 1m0s
[03:49:52.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:152:0)
[03:49:52.753] Timer tick, asking Buildbucket for the build status
[03:49:52.991] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:52.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:153:0) after 1m0s
[03:50:52.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:153:0)
[03:50:52.976] Timer tick, asking Buildbucket for the build status
[03:50:53.068] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:53.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:154:0) after 1m0s
[03:51:53.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:154:0)
[03:51:53.238] Timer tick, asking Buildbucket for the build status
[03:51:53.316] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:53.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:155:0) after 1m0s
[03:52:53.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:155:0)
[03:52:53.382] Timer tick, asking Buildbucket for the build status
[03:52:53.780] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:53.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:156:0) after 1m0s
[03:53:53.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:156:0)
[03:53:53.862] Timer tick, asking Buildbucket for the build status
[03:53:54.104] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:54.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:157:0) after 1m0s
[03:54:54.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:157:0)
[03:54:54.191] Timer tick, asking Buildbucket for the build status
[03:54:54.433] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:54.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:158:0) after 1m0s
[03:55:54.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:158:0)
[03:55:54.501] Timer tick, asking Buildbucket for the build status
[03:55:54.578] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:54.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:159:0) after 1m0s
[03:56:54.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:159:0)
[03:56:54.605] Timer tick, asking Buildbucket for the build status
[03:56:54.733] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:54.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:160:0) after 1m0s
[03:57:54.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:160:0)
[03:57:54.722] Timer tick, asking Buildbucket for the build status
[03:57:54.797] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:54.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:161:0) after 1m0s
[03:58:54.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:161:0)
[03:58:54.814] Timer tick, asking Buildbucket for the build status
[03:58:55.020] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:55.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:162:0) after 1m0s
[03:59:55.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:162:0)
[03:59:55.044] Timer tick, asking Buildbucket for the build status
[03:59:55.460] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:55.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:163:0) after 1m0s
[04:00:55.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:163:0)
[04:00:55.457] Timer tick, asking Buildbucket for the build status
[04:00:55.558] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:55.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:164:0) after 1m0s
[04:01:55.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:164:0)
[04:01:55.580] Timer tick, asking Buildbucket for the build status
[04:01:55.778] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:55.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:165:0) after 1m0s
[04:02:55.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:165:0)
[04:02:55.820] Timer tick, asking Buildbucket for the build status
[04:02:56.089] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:56.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:166:0) after 1m0s
[04:03:56.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:166:0)
[04:03:56.136] Timer tick, asking Buildbucket for the build status
[04:03:56.221] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:56.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:167:0) after 1m0s
[04:04:56.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:167:0)
[04:04:56.251] Timer tick, asking Buildbucket for the build status
[04:04:56.721] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:56.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:168:0) after 1m0s
[04:05:56.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:168:0)
[04:05:56.742] Timer tick, asking Buildbucket for the build status
[04:05:56.813] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:56.813] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:169:0) after 1m0s
[04:06:56.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:169:0)
[04:06:56.850] Timer tick, asking Buildbucket for the build status
[04:06:56.946] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:56.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:170:0) after 1m0s
[04:07:57.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:170:0)
[04:07:57.001] Timer tick, asking Buildbucket for the build status
[04:07:57.087] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:57.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:171:0) after 1m0s
[04:08:57.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:171:0)
[04:08:57.118] Timer tick, asking Buildbucket for the build status
[04:08:57.187] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:57.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:172:0) after 1m0s
[04:09:57.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:172:0)
[04:09:57.202] Timer tick, asking Buildbucket for the build status
[04:09:57.728] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:57.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:173:0) after 1m0s
[04:10:57.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:173:0)
[04:10:57.754] Timer tick, asking Buildbucket for the build status
[04:10:57.838] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:57.838] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:174:0) after 1m0s
[04:11:57.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:174:0)
[04:11:57.826] Timer tick, asking Buildbucket for the build status
[04:11:58.098] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:58.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:175:0) after 1m0s
[04:12:58.144] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:175:0)
[04:12:58.144] Timer tick, asking Buildbucket for the build status
[04:12:58.648] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:58.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:176:0) after 1m0s
[04:13:58.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:176:0)
[04:13:58.702] Timer tick, asking Buildbucket for the build status
[04:13:58.777] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:58.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:177:0) after 1m0s
[04:14:58.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:177:0)
[04:14:58.805] Timer tick, asking Buildbucket for the build status
[04:14:58.936] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:58.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:178:0) after 1m0s
[04:15:58.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:178:0)
[04:15:58.981] Timer tick, asking Buildbucket for the build status
[04:15:59.153] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:59.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:179:0) after 1m0s
[04:16:59.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:179:0)
[04:16:59.207] Timer tick, asking Buildbucket for the build status
[04:16:59.300] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:59.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:180:0) after 1m0s
[04:17:59.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:180:0)
[04:17:59.332] Timer tick, asking Buildbucket for the build status
[04:17:59.401] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:59.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:181:0) after 1m0s
[04:18:59.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:181:0)
[04:18:59.431] Timer tick, asking Buildbucket for the build status
[04:18:59.534] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:59.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:182:0) after 1m0s
[04:19:59.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:182:0)
[04:19:59.564] Timer tick, asking Buildbucket for the build status
[04:19:59.809] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:59.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:183:0) after 1m0s
[04:20:59.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:183:0)
[04:20:59.904] Timer tick, asking Buildbucket for the build status
[04:20:59.987] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:59.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:184:0) after 1m0s
[04:22:00.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:184:0)
[04:22:00.057] Timer tick, asking Buildbucket for the build status
[04:22:00.320] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:00.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:185:0) after 1m0s
[04:23:00.399] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:185:0)
[04:23:00.399] Timer tick, asking Buildbucket for the build status
[04:23:00.482] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:00.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:186:0) after 1m0s
[04:24:00.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:186:0)
[04:24:00.572] Timer tick, asking Buildbucket for the build status
[04:24:00.656] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:00.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:187:0) after 1m0s
[04:25:00.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:187:0)
[04:25:00.730] Timer tick, asking Buildbucket for the build status
[04:25:00.826] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:00.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:188:0) after 1m0s
[04:26:00.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:188:0)
[04:26:00.979] Timer tick, asking Buildbucket for the build status
[04:26:01.058] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:01.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:189:0) after 1m0s
[04:27:01.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:189:0)
[04:27:01.137] Timer tick, asking Buildbucket for the build status
[04:27:01.226] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:01.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:190:0) after 1m0s
[04:28:01.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:190:0)
[04:28:01.415] Timer tick, asking Buildbucket for the build status
[04:28:01.502] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:01.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:191:0) after 1m0s
[04:29:01.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:191:0)
[04:29:01.540] Timer tick, asking Buildbucket for the build status
[04:29:01.654] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:01.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:192:0) after 1m0s
[04:30:01.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:192:0)
[04:30:01.679] Timer tick, asking Buildbucket for the build status
[04:30:02.133] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:02.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:193:0) after 1m0s
[04:31:02.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:193:0)
[04:31:02.196] Timer tick, asking Buildbucket for the build status
[04:31:02.292] Build 8855452765681471952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:02.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9020882827593163520:194:0) after 1m0s
[04:31:11.695] Received PubSub notification, asking Buildbucket for the build status
[04:31:11.789] Build 8855452765681471952: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:31:11.789] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1613190671290430",
    "created_by": "project:nacl",
    "created_ts": "1613179159428655",
    "failure_reason": "BUILD_FAILURE",
    "id": "8855452765681471952",
    "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\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"title\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"title\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-esby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5129, \"got_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-esby\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1613181601131622",
    "status": "COMPLETED",
    "status_changed_ts": "1613181601556395",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5129",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9020882827593163520",
      "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/8855452765681471952/+/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:51b1ca1676b10210",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1613190671345796",
    "url": "https://ci.chromium.org/b/8855452765681471952",
    "utcnow_ts": "1613190671777668"
  }
}
[04:31:11.789] Invocation finished in 3h11m53.567458888s with status FAILED