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

• 35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e (5 years ago)
• 132e918287de5034b0f1b64910988d54b36092c3 (5 years ago)

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

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

[04:26:58.253] New invocation is queued and will start shortly
[04:26:59.465] Starting the invocation (attempt 1)
[04:26:59.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:26:59.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:26:59.511] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9052670555818742656",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\",\"title\":\"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"132e918287de5034b0f1b64910988d54b36092c3\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@132e918287de5034b0f1b64910988d54b36092c3\",\"title\":\"132e918287de5034b0f1b64910988d54b36092c3\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/132e918287de5034b0f1b64910988d54b36092c3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"132e918287de5034b0f1b64910988d54b36092c3\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9052670555818742656",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/132e918287de5034b0f1b64910988d54b36092c3",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/132e918287de5034b0f1b64910988d54b36092c3",
    "gitiles_ref:refs/heads/master"
  ]
}
[04:27:00.312] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1582864019617009",
    "id": "8887240493724291248",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"title\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@132e918287de5034b0f1b64910988d54b36092c3\", \"title\": \"132e918287de5034b0f1b64910988d54b36092c3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/132e918287de5034b0f1b64910988d54b36092c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1582864020201434",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5141",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/132e918287de5034b0f1b64910988d54b36092c3",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/132e918287de5034b0f1b64910988d54b36092c3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052670555818742656",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8887240493724291248/+/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": "1582864020201578",
    "url": "https://ci.chromium.org/b/8887240493724291248",
    "utcnow_ts": "1582864020301588"
  }
}
[04:27:00.312] Task URL: https://ci.chromium.org/b/8887240493724291248
[04:27:00.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:2:0) after 1m0s
[04:28:00.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:2:0)
[04:28:00.371] Timer tick, asking Buildbucket for the build status
[04:28:00.840] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:00.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:3:0) after 1m0s
[04:29:00.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:3:0)
[04:29:00.933] Timer tick, asking Buildbucket for the build status
[04:29:01.177] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:01.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:4:0) after 1m0s
[04:30:01.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:4:0)
[04:30:01.247] Timer tick, asking Buildbucket for the build status
[04:30:01.378] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:01.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:5:0) after 1m0s
[04:31:01.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:5:0)
[04:31:01.428] Timer tick, asking Buildbucket for the build status
[04:31:01.789] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:01.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:6:0) after 1m0s
[04:32:01.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:6:0)
[04:32:01.846] Timer tick, asking Buildbucket for the build status
[04:32:02.134] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:02.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:7:0) after 1m0s
[04:33:02.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:7:0)
[04:33:02.223] Timer tick, asking Buildbucket for the build status
[04:33:02.346] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:02.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:8:0) after 1m0s
[04:34:02.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:8:0)
[04:34:02.392] Timer tick, asking Buildbucket for the build status
[04:34:02.565] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:02.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:9:0) after 1m0s
[04:35:02.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:9:0)
[04:35:02.580] Timer tick, asking Buildbucket for the build status
[04:35:02.887] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:02.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:10:0) after 1m0s
[04:36:03.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:10:0)
[04:36:03.117] Timer tick, asking Buildbucket for the build status
[04:36:03.226] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:03.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:11:0) after 1m0s
[04:37:03.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:11:0)
[04:37:03.271] Timer tick, asking Buildbucket for the build status
[04:37:03.747] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:03.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:12:0) after 1m0s
[04:38:03.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:12:0)
[04:38:03.921] Timer tick, asking Buildbucket for the build status
[04:38:04.036] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:04.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:13:0) after 1m0s
[04:39:04.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:13:0)
[04:39:04.132] Timer tick, asking Buildbucket for the build status
[04:39:04.269] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:04.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:14:0) after 1m0s
[04:40:04.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:14:0)
[04:40:04.274] Timer tick, asking Buildbucket for the build status
[04:40:04.624] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:04.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:15:0) after 1m0s
[04:41:04.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:15:0)
[04:41:04.701] Timer tick, asking Buildbucket for the build status
[04:41:04.815] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:04.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:16:0) after 1m0s
[04:42:04.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:16:0)
[04:42:04.875] Timer tick, asking Buildbucket for the build status
[04:42:05.002] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:05.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:17:0) after 1m0s
[04:43:04.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:17:0)
[04:43:04.995] Timer tick, asking Buildbucket for the build status
[04:43:05.335] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:05.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:18:0) after 1m0s
[04:44:05.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:18:0)
[04:44:05.358] Timer tick, asking Buildbucket for the build status
[04:44:05.776] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:05.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:19:0) after 1m0s
[04:45:05.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:19:0)
[04:45:05.853] Timer tick, asking Buildbucket for the build status
[04:45:06.016] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:06.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:20:0) after 1m0s
[04:46:06.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:20:0)
[04:46:06.030] Timer tick, asking Buildbucket for the build status
[04:46:06.142] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:06.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:21:0) after 1m0s
[04:47:06.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:21:0)
[04:47:06.192] Timer tick, asking Buildbucket for the build status
[04:47:06.294] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:06.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:22:0) after 1m0s
[04:48:06.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:22:0)
[04:48:06.353] Timer tick, asking Buildbucket for the build status
[04:48:06.474] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:06.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:23:0) after 1m0s
[04:49:06.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:23:0)
[04:49:06.499] Timer tick, asking Buildbucket for the build status
[04:49:06.879] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:06.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:24:0) after 1m0s
[04:50:06.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:24:0)
[04:50:06.902] Timer tick, asking Buildbucket for the build status
[04:50:07.263] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:07.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:25:0) after 1m0s
[04:51:07.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:25:0)
[04:51:07.385] Timer tick, asking Buildbucket for the build status
[04:51:07.583] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:07.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:26:0) after 1m0s
[04:52:07.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:26:0)
[04:52:07.621] Timer tick, asking Buildbucket for the build status
[04:52:07.773] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:07.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:27:0) after 1m0s
[04:53:07.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:27:0)
[04:53:07.802] Timer tick, asking Buildbucket for the build status
[04:53:07.918] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:07.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:28:0) after 1m0s
[04:54:07.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:28:0)
[04:54:07.968] Timer tick, asking Buildbucket for the build status
[04:54:08.178] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:08.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:29:0) after 1m0s
[04:55:08.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:29:0)
[04:55:08.240] Timer tick, asking Buildbucket for the build status
[04:55:08.333] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:08.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:30:0) after 1m0s
[04:56:08.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:30:0)
[04:56:08.351] Timer tick, asking Buildbucket for the build status
[04:56:08.478] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:08.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:31:0) after 1m0s
[04:57:08.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:31:0)
[04:57:08.504] Timer tick, asking Buildbucket for the build status
[04:57:08.869] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:08.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:32:0) after 1m0s
[04:58:08.919] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:32:0)
[04:58:08.919] Timer tick, asking Buildbucket for the build status
[04:58:09.033] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:09.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:33:0) after 1m0s
[04:59:09.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:33:0)
[04:59:09.018] Timer tick, asking Buildbucket for the build status
[04:59:09.120] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:09.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:34:0) after 1m0s
[05:00:09.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:34:0)
[05:00:09.222] Timer tick, asking Buildbucket for the build status
[05:00:09.351] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:09.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:35:0) after 1m0s
[05:01:09.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:35:0)
[05:01:09.515] Timer tick, asking Buildbucket for the build status
[05:01:09.802] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:09.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:36:0) after 1m0s
[05:02:09.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:36:0)
[05:02:09.824] Timer tick, asking Buildbucket for the build status
[05:02:09.990] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:09.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:37:0) after 1m0s
[05:03:10.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:37:0)
[05:03:10.017] Timer tick, asking Buildbucket for the build status
[05:03:10.335] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:10.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:38:0) after 1m0s
[05:04:10.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:38:0)
[05:04:10.365] Timer tick, asking Buildbucket for the build status
[05:04:10.497] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:10.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:39:0) after 1m0s
[05:05:10.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:39:0)
[05:05:10.614] Timer tick, asking Buildbucket for the build status
[05:05:10.767] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:10.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:40:0) after 1m0s
[05:06:10.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:40:0)
[05:06:10.838] Timer tick, asking Buildbucket for the build status
[05:06:10.969] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:10.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:41:0) after 1m0s
[05:07:11.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:41:0)
[05:07:11.086] Timer tick, asking Buildbucket for the build status
[05:07:11.305] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:11.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:42:0) after 1m0s
[05:08:11.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:42:0)
[05:08:11.408] Timer tick, asking Buildbucket for the build status
[05:08:11.579] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:11.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:43:0) after 1m0s
[05:09:11.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:43:0)
[05:09:11.613] Timer tick, asking Buildbucket for the build status
[05:09:11.747] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:11.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:44:0) after 1m0s
[05:10:11.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:44:0)
[05:10:11.793] Timer tick, asking Buildbucket for the build status
[05:10:12.223] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:12.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:45:0) after 1m0s
[05:11:12.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:45:0)
[05:11:12.327] Timer tick, asking Buildbucket for the build status
[05:11:12.464] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:12.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:46:0) after 1m0s
[05:12:12.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:46:0)
[05:12:12.576] Timer tick, asking Buildbucket for the build status
[05:12:12.715] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:12.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:47:0) after 1m0s
[05:13:12.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:47:0)
[05:13:12.885] Timer tick, asking Buildbucket for the build status
[05:13:13.025] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:13.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:48:0) after 1m0s
[05:14:13.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:48:0)
[05:14:13.129] Timer tick, asking Buildbucket for the build status
[05:14:13.249] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:13.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:49:0) after 1m0s
[05:15:13.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:49:0)
[05:15:13.355] Timer tick, asking Buildbucket for the build status
[05:15:13.520] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:13.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:50:0) after 1m0s
[05:16:13.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:50:0)
[05:16:13.600] Timer tick, asking Buildbucket for the build status
[05:16:13.765] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:13.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:51:0) after 1m0s
[05:17:13.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:51:0)
[05:17:13.965] Timer tick, asking Buildbucket for the build status
[05:17:14.113] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:14.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:52:0) after 1m0s
[05:18:14.209] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:52:0)
[05:18:14.209] Timer tick, asking Buildbucket for the build status
[05:18:14.357] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:18:14.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:53:0) after 1m0s
[05:19:14.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:53:0)
[05:19:14.470] Timer tick, asking Buildbucket for the build status
[05:19:14.978] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:19:14.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:54:0) after 1m0s
[05:20:15.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:54:0)
[05:20:15.106] Timer tick, asking Buildbucket for the build status
[05:20:15.459] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:15.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:55:0) after 1m0s
[05:21:15.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:55:0)
[05:21:15.522] Timer tick, asking Buildbucket for the build status
[05:21:15.630] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:15.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:56:0) after 1m0s
[05:22:15.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:56:0)
[05:22:15.675] Timer tick, asking Buildbucket for the build status
[05:22:16.213] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:16.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:57:0) after 1m0s
[05:23:16.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:57:0)
[05:23:16.281] Timer tick, asking Buildbucket for the build status
[05:23:16.698] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:16.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:58:0) after 1m0s
[05:24:16.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:58:0)
[05:24:16.749] Timer tick, asking Buildbucket for the build status
[05:24:16.956] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:16.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:59:0) after 1m0s
[05:25:16.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:59:0)
[05:25:16.949] Timer tick, asking Buildbucket for the build status
[05:25:17.402] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:25:17.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:60:0) after 1m0s
[05:26:17.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:60:0)
[05:26:17.486] Timer tick, asking Buildbucket for the build status
[05:26:17.599] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:17.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:61:0) after 1m0s
[05:27:17.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:61:0)
[05:27:17.619] Timer tick, asking Buildbucket for the build status
[05:27:17.831] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:17.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:62:0) after 1m0s
[05:28:17.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:62:0)
[05:28:17.868] Timer tick, asking Buildbucket for the build status
[05:28:18.036] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:18.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:63:0) after 1m0s
[05:29:18.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:63:0)
[05:29:18.104] Timer tick, asking Buildbucket for the build status
[05:29:18.481] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:18.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:64:0) after 1m0s
[05:30:18.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:64:0)
[05:30:18.517] Timer tick, asking Buildbucket for the build status
[05:30:18.629] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:18.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:65:0) after 1m0s
[05:31:18.662] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:65:0)
[05:31:18.662] Timer tick, asking Buildbucket for the build status
[05:31:18.915] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:18.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:66:0) after 1m0s
[05:32:18.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:66:0)
[05:32:18.998] Timer tick, asking Buildbucket for the build status
[05:32:19.111] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:19.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:67:0) after 1m0s
[05:33:19.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:67:0)
[05:33:19.205] Timer tick, asking Buildbucket for the build status
[05:33:19.382] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:19.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:68:0) after 1m0s
[05:34:19.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:68:0)
[05:34:19.555] Timer tick, asking Buildbucket for the build status
[05:34:19.790] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:19.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:69:0) after 1m0s
[05:35:19.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:69:0)
[05:35:19.825] Timer tick, asking Buildbucket for the build status
[05:35:20.305] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:20.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:70:0) after 1m0s
[05:36:20.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:70:0)
[05:36:20.352] Timer tick, asking Buildbucket for the build status
[05:36:20.595] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:20.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:71:0) after 1m0s
[05:37:20.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:71:0)
[05:37:20.736] Timer tick, asking Buildbucket for the build status
[05:37:20.958] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:20.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:72:0) after 1m0s
[05:38:20.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:72:0)
[05:38:20.984] Timer tick, asking Buildbucket for the build status
[05:38:21.140] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:21.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:73:0) after 1m0s
[05:39:21.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:73:0)
[05:39:21.173] Timer tick, asking Buildbucket for the build status
[05:39:21.286] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:21.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:74:0) after 1m0s
[05:40:21.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:74:0)
[05:40:21.365] Timer tick, asking Buildbucket for the build status
[05:40:21.592] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:21.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:75:0) after 1m0s
[05:41:21.715] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:75:0)
[05:41:21.715] Timer tick, asking Buildbucket for the build status
[05:41:22.005] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:22.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:76:0) after 1m0s
[05:42:22.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:76:0)
[05:42:22.041] Timer tick, asking Buildbucket for the build status
[05:42:22.171] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:22.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:77:0) after 1m0s
[05:43:22.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:77:0)
[05:43:22.193] Timer tick, asking Buildbucket for the build status
[05:43:22.318] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:22.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:78:0) after 1m0s
[05:44:22.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:78:0)
[05:44:22.356] Timer tick, asking Buildbucket for the build status
[05:44:22.663] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:22.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:79:0) after 1m0s
[05:45:22.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:79:0)
[05:45:22.747] Timer tick, asking Buildbucket for the build status
[05:45:23.078] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:23.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:80:0) after 1m0s
[05:46:23.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:80:0)
[05:46:23.057] Timer tick, asking Buildbucket for the build status
[05:46:23.307] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:23.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:81:0) after 1m0s
[05:47:23.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:81:0)
[05:47:23.338] Timer tick, asking Buildbucket for the build status
[05:47:23.524] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:23.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:82:0) after 1m0s
[05:48:23.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:82:0)
[05:48:23.564] Timer tick, asking Buildbucket for the build status
[05:48:23.726] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:23.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:83:0) after 1m0s
[05:49:23.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:83:0)
[05:49:23.766] Timer tick, asking Buildbucket for the build status
[05:49:23.883] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:23.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:84:0) after 1m0s
[05:50:23.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:84:0)
[05:50:23.909] Timer tick, asking Buildbucket for the build status
[05:50:23.994] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:23.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:85:0) after 1m0s
[05:51:24.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:85:0)
[05:51:24.110] Timer tick, asking Buildbucket for the build status
[05:51:24.439] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:24.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:86:0) after 1m0s
[05:52:24.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:86:0)
[05:52:24.495] Timer tick, asking Buildbucket for the build status
[05:52:24.598] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:24.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:87:0) after 1m0s
[05:53:24.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:87:0)
[05:53:24.628] Timer tick, asking Buildbucket for the build status
[05:53:24.957] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:24.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:88:0) after 1m0s
[05:54:24.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:88:0)
[05:54:24.993] Timer tick, asking Buildbucket for the build status
[05:54:25.169] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:25.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:89:0) after 1m0s
[05:55:25.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:89:0)
[05:55:25.186] Timer tick, asking Buildbucket for the build status
[05:55:25.365] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:25.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:90:0) after 1m0s
[05:56:25.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:90:0)
[05:56:25.441] Timer tick, asking Buildbucket for the build status
[05:56:25.649] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:25.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:91:0) after 1m0s
[05:57:25.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:91:0)
[05:57:25.913] Timer tick, asking Buildbucket for the build status
[05:57:26.137] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:26.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:92:0) after 1m0s
[05:58:26.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:92:0)
[05:58:26.150] Timer tick, asking Buildbucket for the build status
[05:58:26.329] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:26.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:93:0) after 1m0s
[05:59:26.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:93:0)
[05:59:26.358] Timer tick, asking Buildbucket for the build status
[05:59:26.701] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:26.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:94:0) after 1m0s
[06:00:26.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:94:0)
[06:00:26.847] Timer tick, asking Buildbucket for the build status
[06:00:26.957] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:26.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:95:0) after 1m0s
[06:01:27.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:95:0)
[06:01:27.086] Timer tick, asking Buildbucket for the build status
[06:01:27.442] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:27.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:96:0) after 1m0s
[06:02:27.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:96:0)
[06:02:27.497] Timer tick, asking Buildbucket for the build status
[06:02:27.608] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:27.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:97:0) after 1m0s
[06:03:27.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:97:0)
[06:03:27.645] Timer tick, asking Buildbucket for the build status
[06:03:27.761] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:27.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:98:0) after 1m0s
[06:04:27.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:98:0)
[06:04:27.978] Timer tick, asking Buildbucket for the build status
[06:04:28.097] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:28.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:99:0) after 1m0s
[06:05:28.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:99:0)
[06:05:28.242] Timer tick, asking Buildbucket for the build status
[06:05:28.658] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:28.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:100:0) after 1m0s
[06:06:28.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:100:0)
[06:06:28.974] Timer tick, asking Buildbucket for the build status
[06:06:29.251] Build 8887240493724291248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:29.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:101:0) after 1m0s
[06:06:40.657] Received PubSub notification, asking Buildbucket for the build status
[06:06:40.747] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:29.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:101:0)
[06:07:29.286] Timer tick, asking Buildbucket for the build status
[06:07:29.510] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:29.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:103:0) after 1m0s
[06:08:29.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:103:0)
[06:08:29.514] Timer tick, asking Buildbucket for the build status
[06:08:29.844] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:29.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:104:0) after 1m0s
[06:09:29.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:104:0)
[06:09:29.952] Timer tick, asking Buildbucket for the build status
[06:09:30.156] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:30.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:105:0) after 1m0s
[06:10:30.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:105:0)
[06:10:30.235] Timer tick, asking Buildbucket for the build status
[06:10:30.393] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:30.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:106:0) after 1m0s
[06:11:30.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:106:0)
[06:11:30.418] Timer tick, asking Buildbucket for the build status
[06:11:30.528] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:30.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:107:0) after 1m0s
[06:12:30.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:107:0)
[06:12:30.860] Timer tick, asking Buildbucket for the build status
[06:12:31.022] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:31.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:108:0) after 1m0s
[06:13:31.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:108:0)
[06:13:31.216] Timer tick, asking Buildbucket for the build status
[06:13:31.372] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:31.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:109:0) after 1m0s
[06:14:31.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:109:0)
[06:14:31.445] Timer tick, asking Buildbucket for the build status
[06:14:31.603] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:31.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:110:0) after 1m0s
[06:15:31.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:110:0)
[06:15:31.680] Timer tick, asking Buildbucket for the build status
[06:15:31.857] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:31.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:111:0) after 1m0s
[06:16:32.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:111:0)
[06:16:32.002] Timer tick, asking Buildbucket for the build status
[06:16:32.258] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:32.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:112:0) after 1m0s
[06:17:32.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:112:0)
[06:17:32.405] Timer tick, asking Buildbucket for the build status
[06:17:32.492] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:32.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:113:0) after 1m0s
[06:18:32.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:113:0)
[06:18:32.532] Timer tick, asking Buildbucket for the build status
[06:18:32.787] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:32.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:114:0) after 1m0s
[06:19:32.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:114:0)
[06:19:32.844] Timer tick, asking Buildbucket for the build status
[06:19:33.042] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:33.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:115:0) after 1m0s
[06:20:33.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:115:0)
[06:20:33.044] Timer tick, asking Buildbucket for the build status
[06:20:33.223] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:33.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:116:0) after 1m0s
[06:21:33.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:116:0)
[06:21:33.674] Timer tick, asking Buildbucket for the build status
[06:21:33.862] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:33.862] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:117:0) after 1m0s
[06:22:34.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:117:0)
[06:22:34.115] Timer tick, asking Buildbucket for the build status
[06:22:34.293] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:34.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:118:0) after 1m0s
[06:23:34.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:118:0)
[06:23:34.323] Timer tick, asking Buildbucket for the build status
[06:23:34.464] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:34.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:119:0) after 1m0s
[06:24:34.641] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:119:0)
[06:24:34.641] Timer tick, asking Buildbucket for the build status
[06:24:35.114] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:35.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:120:0) after 1m0s
[06:25:35.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:120:0)
[06:25:35.169] Timer tick, asking Buildbucket for the build status
[06:25:35.323] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:35.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:121:0) after 1m0s
[06:26:35.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:121:0)
[06:26:35.356] Timer tick, asking Buildbucket for the build status
[06:26:35.571] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:35.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:122:0) after 1m0s
[06:27:35.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:122:0)
[06:27:35.640] Timer tick, asking Buildbucket for the build status
[06:27:35.839] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:35.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:123:0) after 1m0s
[06:28:36.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:123:0)
[06:28:36.147] Timer tick, asking Buildbucket for the build status
[06:28:36.353] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:36.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:124:0) after 1m0s
[06:29:36.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:124:0)
[06:29:36.443] Timer tick, asking Buildbucket for the build status
[06:29:36.746] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:125:0) after 1m0s
[06:30:36.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:125:0)
[06:30:36.909] Timer tick, asking Buildbucket for the build status
[06:30:37.092] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:37.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:126:0) after 1m0s
[06:31:37.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:126:0)
[06:31:37.240] Timer tick, asking Buildbucket for the build status
[06:31:37.474] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:37.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:127:0) after 1m0s
[06:32:37.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:127:0)
[06:32:37.626] Timer tick, asking Buildbucket for the build status
[06:32:37.954] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:37.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:128:0) after 1m0s
[06:33:38.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:128:0)
[06:33:38.063] Timer tick, asking Buildbucket for the build status
[06:33:38.194] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:38.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:129:0) after 1m0s
[06:34:38.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:129:0)
[06:34:38.330] Timer tick, asking Buildbucket for the build status
[06:34:38.604] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:38.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:130:0) after 1m0s
[06:35:38.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:130:0)
[06:35:38.681] Timer tick, asking Buildbucket for the build status
[06:35:38.775] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:38.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:131:0) after 1m0s
[06:36:39.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:131:0)
[06:36:39.032] Timer tick, asking Buildbucket for the build status
[06:36:39.271] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:39.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:132:0) after 1m0s
[06:37:39.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:132:0)
[06:37:39.476] Timer tick, asking Buildbucket for the build status
[06:37:39.897] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:39.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:133:0) after 1m0s
[06:38:39.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:133:0)
[06:38:39.952] Timer tick, asking Buildbucket for the build status
[06:38:40.125] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:40.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:134:0) after 1m0s
[06:39:40.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:134:0)
[06:39:40.263] Timer tick, asking Buildbucket for the build status
[06:39:40.369] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:40.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:135:0) after 1m0s
[06:40:40.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:135:0)
[06:40:40.400] Timer tick, asking Buildbucket for the build status
[06:40:40.561] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:40.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:136:0) after 1m0s
[06:41:40.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:136:0)
[06:41:40.587] Timer tick, asking Buildbucket for the build status
[06:41:40.693] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:40.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:137:0) after 1m0s
[06:42:40.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:137:0)
[06:42:40.780] Timer tick, asking Buildbucket for the build status
[06:42:40.940] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:40.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:138:0) after 1m0s
[06:43:41.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:138:0)
[06:43:41.053] Timer tick, asking Buildbucket for the build status
[06:43:41.250] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:41.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:139:0) after 1m0s
[06:44:41.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:139:0)
[06:44:41.271] Timer tick, asking Buildbucket for the build status
[06:44:41.446] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:41.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:140:0) after 1m0s
[06:45:41.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:140:0)
[06:45:41.469] Timer tick, asking Buildbucket for the build status
[06:45:41.631] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:41.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:141:0) after 1m0s
[06:46:42.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:141:0)
[06:46:42.499] Timer tick, asking Buildbucket for the build status
[06:46:42.688] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:42.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:142:0) after 1m0s
[06:47:42.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:142:0)
[06:47:42.781] Timer tick, asking Buildbucket for the build status
[06:47:42.931] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:42.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:143:0) after 1m0s
[06:48:42.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:143:0)
[06:48:42.986] Timer tick, asking Buildbucket for the build status
[06:48:43.359] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:43.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:144:0) after 1m0s
[06:49:43.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:144:0)
[06:49:43.829] Timer tick, asking Buildbucket for the build status
[06:49:44.059] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:44.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:145:0) after 1m0s
[06:50:44.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:145:0)
[06:50:44.243] Timer tick, asking Buildbucket for the build status
[06:50:44.531] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:146:0) after 1m0s
[06:51:44.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:146:0)
[06:51:44.753] Timer tick, asking Buildbucket for the build status
[06:51:45.171] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:45.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:147:0) after 1m0s
[06:52:45.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:147:0)
[06:52:45.323] Timer tick, asking Buildbucket for the build status
[06:52:45.537] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:45.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:148:0) after 1m0s
[06:53:45.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:148:0)
[06:53:45.579] Timer tick, asking Buildbucket for the build status
[06:53:46.079] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:46.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:149:0) after 1m0s
[06:54:46.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:149:0)
[06:54:46.059] Timer tick, asking Buildbucket for the build status
[06:54:46.235] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:46.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:150:0) after 1m0s
[06:55:46.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:150:0)
[06:55:46.274] Timer tick, asking Buildbucket for the build status
[06:55:46.607] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:46.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:151:0) after 1m0s
[06:56:46.632] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:151:0)
[06:56:46.632] Timer tick, asking Buildbucket for the build status
[06:56:46.840] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:46.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:152:0) after 1m0s
[06:57:46.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:152:0)
[06:57:46.913] Timer tick, asking Buildbucket for the build status
[06:57:47.204] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:47.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:153:0) after 1m0s
[06:58:47.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:153:0)
[06:58:47.280] Timer tick, asking Buildbucket for the build status
[06:58:47.808] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:47.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:154:0) after 1m0s
[06:59:47.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:154:0)
[06:59:47.879] Timer tick, asking Buildbucket for the build status
[06:59:48.011] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:48.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:155:0) after 1m0s
[07:00:48.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:155:0)
[07:00:48.004] Timer tick, asking Buildbucket for the build status
[07:00:48.159] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:48.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:156:0) after 1m0s
[07:01:48.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:156:0)
[07:01:48.192] Timer tick, asking Buildbucket for the build status
[07:01:48.403] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:48.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:157:0) after 1m0s
[07:02:48.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:157:0)
[07:02:48.435] Timer tick, asking Buildbucket for the build status
[07:02:48.708] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:48.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:158:0) after 1m0s
[07:03:48.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:158:0)
[07:03:48.773] Timer tick, asking Buildbucket for the build status
[07:03:49.099] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:49.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:159:0) after 1m0s
[07:04:49.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:159:0)
[07:04:49.134] Timer tick, asking Buildbucket for the build status
[07:04:49.389] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:49.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:160:0) after 1m0s
[07:05:49.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:160:0)
[07:05:49.492] Timer tick, asking Buildbucket for the build status
[07:05:49.995] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:49.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:161:0) after 1m0s
[07:06:50.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:161:0)
[07:06:50.034] Timer tick, asking Buildbucket for the build status
[07:06:50.408] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:50.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:162:0) after 1m0s
[07:07:50.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:162:0)
[07:07:50.550] Timer tick, asking Buildbucket for the build status
[07:07:50.651] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:50.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:163:0) after 1m0s
[07:08:50.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:163:0)
[07:08:50.679] Timer tick, asking Buildbucket for the build status
[07:08:51.026] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:51.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:164:0) after 1m0s
[07:09:51.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:164:0)
[07:09:51.051] Timer tick, asking Buildbucket for the build status
[07:09:51.348] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:51.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:165:0) after 1m0s
[07:10:51.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:165:0)
[07:10:51.379] Timer tick, asking Buildbucket for the build status
[07:10:51.518] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:51.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:166:0) after 1m0s
[07:11:51.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:166:0)
[07:11:51.562] Timer tick, asking Buildbucket for the build status
[07:11:52.506] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:52.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:167:0) after 1m0s
[07:12:52.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:167:0)
[07:12:52.680] Timer tick, asking Buildbucket for the build status
[07:12:54.119] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:54.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:168:0) after 1m0s
[07:13:54.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:168:0)
[07:13:54.132] Timer tick, asking Buildbucket for the build status
[07:13:54.484] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:54.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:169:0) after 1m0s
[07:14:54.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:169:0)
[07:14:54.572] Timer tick, asking Buildbucket for the build status
[07:14:54.852] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:54.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:170:0) after 1m0s
[07:15:54.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:170:0)
[07:15:54.882] Timer tick, asking Buildbucket for the build status
[07:15:55.225] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:55.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:171:0) after 1m0s
[07:16:55.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:171:0)
[07:16:55.255] Timer tick, asking Buildbucket for the build status
[07:16:55.447] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:55.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:172:0) after 1m0s
[07:17:55.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:172:0)
[07:17:55.525] Timer tick, asking Buildbucket for the build status
[07:17:55.998] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:55.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:173:0) after 1m0s
[07:18:56.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:173:0)
[07:18:56.214] Timer tick, asking Buildbucket for the build status
[07:18:56.874] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:56.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:174:0) after 1m0s
[07:19:57.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:174:0)
[07:19:57.112] Timer tick, asking Buildbucket for the build status
[07:19:57.249] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:57.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:175:0) after 1m0s
[07:20:57.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:175:0)
[07:20:57.277] Timer tick, asking Buildbucket for the build status
[07:20:57.554] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:57.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:176:0) after 1m0s
[07:21:57.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:176:0)
[07:21:57.627] Timer tick, asking Buildbucket for the build status
[07:21:58.259] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:58.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:177:0) after 1m0s
[07:22:58.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:177:0)
[07:22:58.498] Timer tick, asking Buildbucket for the build status
[07:22:59.194] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:59.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:178:0) after 1m0s
[07:23:59.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:178:0)
[07:23:59.262] Timer tick, asking Buildbucket for the build status
[07:23:59.508] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:59.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:179:0) after 1m0s
[07:24:59.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:179:0)
[07:24:59.675] Timer tick, asking Buildbucket for the build status
[07:24:59.997] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:59.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:180:0) after 1m0s
[07:26:00.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:180:0)
[07:26:00.032] Timer tick, asking Buildbucket for the build status
[07:26:00.330] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:00.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:181:0) after 1m0s
[07:27:00.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:181:0)
[07:27:00.393] Timer tick, asking Buildbucket for the build status
[07:27:00.544] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:00.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:182:0) after 1m0s
[07:28:00.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:182:0)
[07:28:00.587] Timer tick, asking Buildbucket for the build status
[07:28:00.844] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:00.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:183:0) after 1m0s
[07:29:00.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:183:0)
[07:29:00.911] Timer tick, asking Buildbucket for the build status
[07:29:02.518] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:02.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:184:0) after 1m0s
[07:30:02.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:184:0)
[07:30:02.581] Timer tick, asking Buildbucket for the build status
[07:30:02.717] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:02.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:185:0) after 1m0s
[07:31:02.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:185:0)
[07:31:02.778] Timer tick, asking Buildbucket for the build status
[07:31:03.109] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:03.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:186:0) after 1m0s
[07:32:03.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:186:0)
[07:32:03.169] Timer tick, asking Buildbucket for the build status
[07:32:03.802] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:03.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:187:0) after 1m0s
[07:33:03.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:187:0)
[07:33:03.848] Timer tick, asking Buildbucket for the build status
[07:33:04.603] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:04.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:188:0) after 1m0s
[07:34:04.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:188:0)
[07:34:04.646] Timer tick, asking Buildbucket for the build status
[07:34:04.762] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:04.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:189:0) after 1m0s
[07:35:04.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:189:0)
[07:35:04.824] Timer tick, asking Buildbucket for the build status
[07:35:05.394] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:05.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:190:0) after 1m0s
[07:36:05.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:190:0)
[07:36:05.418] Timer tick, asking Buildbucket for the build status
[07:36:05.553] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:05.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:191:0) after 1m0s
[07:37:05.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:191:0)
[07:37:05.750] Timer tick, asking Buildbucket for the build status
[07:37:05.878] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:05.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:192:0) after 1m0s
[07:38:05.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:192:0)
[07:38:05.980] Timer tick, asking Buildbucket for the build status
[07:38:06.408] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:06.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:193:0) after 1m0s
[07:39:06.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:193:0)
[07:39:06.492] Timer tick, asking Buildbucket for the build status
[07:39:06.726] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:06.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:194:0) after 1m0s
[07:40:06.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:194:0)
[07:40:06.835] Timer tick, asking Buildbucket for the build status
[07:40:07.122] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:07.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:195:0) after 1m0s
[07:41:07.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:195:0)
[07:41:07.156] Timer tick, asking Buildbucket for the build status
[07:41:07.512] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:07.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:196:0) after 1m0s
[07:42:07.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:196:0)
[07:42:07.793] Timer tick, asking Buildbucket for the build status
[07:42:08.374] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:08.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:197:0) after 1m0s
[07:43:08.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:197:0)
[07:43:08.601] Timer tick, asking Buildbucket for the build status
[07:43:08.855] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:08.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:198:0) after 1m0s
[07:44:09.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:198:0)
[07:44:09.192] Timer tick, asking Buildbucket for the build status
[07:44:11.059] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:11.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:199:0) after 1m0s
[07:45:11.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:199:0)
[07:45:11.212] Timer tick, asking Buildbucket for the build status
[07:45:11.856] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:11.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:200:0) after 1m0s
[07:46:11.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:200:0)
[07:46:11.957] Timer tick, asking Buildbucket for the build status
[07:46:12.184] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:12.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:201:0) after 1m0s
[07:47:12.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:201:0)
[07:47:12.394] Timer tick, asking Buildbucket for the build status
[07:47:12.625] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:12.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:202:0) after 1m0s
[07:48:12.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:202:0)
[07:48:12.858] Timer tick, asking Buildbucket for the build status
[07:48:14.805] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:14.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:203:0) after 1m0s
[07:49:15.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:203:0)
[07:49:15.092] Timer tick, asking Buildbucket for the build status
[07:49:16.837] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:16.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:204:0) after 1m0s
[07:50:17.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:204:0)
[07:50:17.056] Timer tick, asking Buildbucket for the build status
[07:50:17.374] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:17.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:205:0) after 1m0s
[07:51:17.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:205:0)
[07:51:17.536] Timer tick, asking Buildbucket for the build status
[07:51:17.685] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:17.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:206:0) after 1m0s
[07:52:17.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:206:0)
[07:52:17.788] Timer tick, asking Buildbucket for the build status
[07:52:18.179] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:18.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:207:0) after 1m0s
[07:53:18.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:207:0)
[07:53:18.464] Timer tick, asking Buildbucket for the build status
[07:53:19.488] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:19.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:208:0) after 1m0s
[07:54:19.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:208:0)
[07:54:19.706] Timer tick, asking Buildbucket for the build status
[07:54:20.013] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:20.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:209:0) after 1m0s
[07:55:20.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:209:0)
[07:55:20.103] Timer tick, asking Buildbucket for the build status
[07:55:20.316] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:20.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:210:0) after 1m0s
[07:56:20.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:210:0)
[07:56:20.449] Timer tick, asking Buildbucket for the build status
[07:56:20.668] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:20.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:211:0) after 1m0s
[07:57:20.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:211:0)
[07:57:20.816] Timer tick, asking Buildbucket for the build status
[07:57:21.527] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:212:0) after 1m0s
[07:58:21.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:212:0)
[07:58:21.711] Timer tick, asking Buildbucket for the build status
[07:58:24.885] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:24.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:213:0) after 1m0s
[07:59:24.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:213:0)
[07:59:24.949] Timer tick, asking Buildbucket for the build status
[07:59:26.072] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:214:0) after 1m0s
[08:00:26.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:214:0)
[08:00:26.247] Timer tick, asking Buildbucket for the build status
[08:00:26.659] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:26.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:215:0) after 1m0s
[08:01:26.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:215:0)
[08:01:26.948] Timer tick, asking Buildbucket for the build status
[08:01:28.663] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:28.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:216:0) after 1m0s
[08:02:28.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:216:0)
[08:02:28.976] Timer tick, asking Buildbucket for the build status
[08:02:29.162] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:29.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:217:0) after 1m0s
[08:03:29.370] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:217:0)
[08:03:29.370] Timer tick, asking Buildbucket for the build status
[08:03:30.052] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:30.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:218:0) after 1m0s
[08:04:30.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:218:0)
[08:04:30.277] Timer tick, asking Buildbucket for the build status
[08:04:31.948] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:31.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:219:0) after 1m0s
[08:05:32.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:219:0)
[08:05:32.334] Timer tick, asking Buildbucket for the build status
[08:05:33.534] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:33.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:220:0) after 1m0s
[08:06:33.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:220:0)
[08:06:33.743] Timer tick, asking Buildbucket for the build status
[08:06:34.553] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:34.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:221:0) after 1m0s
[08:07:34.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:221:0)
[08:07:34.643] Timer tick, asking Buildbucket for the build status
[08:07:34.845] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:34.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:222:0) after 1m0s
[08:08:34.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:222:0)
[08:08:34.955] Timer tick, asking Buildbucket for the build status
[08:08:35.212] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:35.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:223:0) after 1m0s
[08:09:35.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:223:0)
[08:09:35.294] Timer tick, asking Buildbucket for the build status
[08:09:35.697] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:35.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:224:0) after 1m0s
[08:10:35.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:224:0)
[08:10:35.806] Timer tick, asking Buildbucket for the build status
[08:10:36.078] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:36.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:225:0) after 1m0s
[08:11:36.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:225:0)
[08:11:36.373] Timer tick, asking Buildbucket for the build status
[08:11:36.571] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:36.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:226:0) after 1m0s
[08:12:36.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:226:0)
[08:12:36.677] Timer tick, asking Buildbucket for the build status
[08:12:36.848] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:36.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:227:0) after 1m0s
[08:13:37.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:227:0)
[08:13:37.048] Timer tick, asking Buildbucket for the build status
[08:13:38.346] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:38.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:228:0) after 1m0s
[08:14:38.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:228:0)
[08:14:38.455] Timer tick, asking Buildbucket for the build status
[08:14:39.269] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:39.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:229:0) after 1m0s
[08:15:39.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:229:0)
[08:15:39.390] Timer tick, asking Buildbucket for the build status
[08:15:39.940] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:39.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:230:0) after 1m0s
[08:16:40.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:230:0)
[08:16:40.085] Timer tick, asking Buildbucket for the build status
[08:16:41.103] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:41.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:231:0) after 1m0s
[08:17:41.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:231:0)
[08:17:41.189] Timer tick, asking Buildbucket for the build status
[08:17:42.019] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:42.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:232:0) after 1m0s
[08:18:42.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:232:0)
[08:18:42.103] Timer tick, asking Buildbucket for the build status
[08:18:42.392] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:42.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:233:0) after 1m0s
[08:19:42.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:233:0)
[08:19:42.546] Timer tick, asking Buildbucket for the build status
[08:19:43.207] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:43.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:234:0) after 1m0s
[08:20:43.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:234:0)
[08:20:43.332] Timer tick, asking Buildbucket for the build status
[08:20:43.549] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:43.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:235:0) after 1m0s
[08:21:43.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:235:0)
[08:21:43.576] Timer tick, asking Buildbucket for the build status
[08:21:43.722] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:43.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:236:0) after 1m0s
[08:22:44.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:236:0)
[08:22:44.336] Timer tick, asking Buildbucket for the build status
[08:22:44.734] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:44.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:237:0) after 1m0s
[08:23:44.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:237:0)
[08:23:44.885] Timer tick, asking Buildbucket for the build status
[08:23:45.045] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:45.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:238:0) after 1m0s
[08:24:45.122] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:238:0)
[08:24:45.122] Timer tick, asking Buildbucket for the build status
[08:24:45.598] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:45.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:239:0) after 1m0s
[08:25:45.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:239:0)
[08:25:45.600] Timer tick, asking Buildbucket for the build status
[08:25:46.192] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:46.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:240:0) after 1m0s
[08:26:46.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:240:0)
[08:26:46.348] Timer tick, asking Buildbucket for the build status
[08:26:46.572] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:46.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:241:0) after 1m0s
[08:27:46.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:241:0)
[08:27:46.666] Timer tick, asking Buildbucket for the build status
[08:27:46.891] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:46.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:242:0) after 1m0s
[08:28:46.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:242:0)
[08:28:46.990] Timer tick, asking Buildbucket for the build status
[08:28:48.420] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:48.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:243:0) after 1m0s
[08:29:48.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:243:0)
[08:29:48.488] Timer tick, asking Buildbucket for the build status
[08:29:48.762] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:48.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:244:0) after 1m0s
[08:30:48.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:244:0)
[08:30:48.858] Timer tick, asking Buildbucket for the build status
[08:30:49.304] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:49.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:245:0) after 1m0s
[08:31:49.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:245:0)
[08:31:49.389] Timer tick, asking Buildbucket for the build status
[08:31:49.536] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:49.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:246:0) after 1m0s
[08:32:49.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:246:0)
[08:32:49.582] Timer tick, asking Buildbucket for the build status
[08:32:50.388] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:50.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:247:0) after 1m0s
[08:33:50.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:247:0)
[08:33:50.398] Timer tick, asking Buildbucket for the build status
[08:33:50.547] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:50.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:248:0) after 1m0s
[08:34:50.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:248:0)
[08:34:50.717] Timer tick, asking Buildbucket for the build status
[08:34:51.063] Build 8887240493724291248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:51.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9052670555818742656:249:0) after 1m0s
[08:35:11.710] Received PubSub notification, asking Buildbucket for the build status
[08:35:11.885] Build 8887240493724291248: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:35:11.885] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1582878911182594",
    "created_by": "project:nacl",
    "created_ts": "1582864019617009",
    "failure_reason": "BUILD_FAILURE",
    "id": "8887240493724291248",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"title\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@132e918287de5034b0f1b64910988d54b36092c3\", \"title\": \"132e918287de5034b0f1b64910988d54b36092c3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/132e918287de5034b0f1b64910988d54b36092c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"title\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@132e918287de5034b0f1b64910988d54b36092c3\", \"title\": \"132e918287de5034b0f1b64910988d54b36092c3\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/132e918287de5034b0f1b64910988d54b36092c3\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-bddb\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5141, \"got_revision\": \"132e918287de5034b0f1b64910988d54b36092c3\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"132e918287de5034b0f1b64910988d54b36092c3\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-bddb\"], \"image\": [\"chrome-xenial-20022300-4c0e34b5b5e\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4838-b69615e\"], \"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": "1582869999866550",
    "status": "COMPLETED",
    "status_changed_ts": "1582870000313975",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5141",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/132e918287de5034b0f1b64910988d54b36092c3",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/132e918287de5034b0f1b64910988d54b36092c3",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052670555818742656",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8887240493724291248/+/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:4aa2ddbc938e8610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1582878911322315",
    "url": "https://ci.chromium.org/b/8887240493724291248",
    "utcnow_ts": "1582878911860934"
  }
}
[08:35:11.885] Invocation finished in 4h8m13.649013493s with status FAILED