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

• 84d6fbfa44f1de95e21107766fc35fa1f6ac0897 (6 years ago)

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

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

[06:33:06.294] New invocation is queued and will start shortly
[06:33:07.678] Starting the invocation (attempt 1)
[06:33:07.712] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:33:07.713] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:33:07.713] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9089444988512989056",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
  "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:9089444988512989056",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
    "gitiles_ref:refs/heads/master"
  ]
}
[06:33:09.246] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547793187848335",
    "id": "8924014926217103120",
    "lease_expiration_ts": "1547944388890607",
    "lease_key": "1445686634",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1547793189043151",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5112",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089444988512989056",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5112",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8924014926217103120",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924014926217103120/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089444988512989056",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:42787b4df5a3f810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547793189043251",
    "url": "https://ci.chromium.org/b/8924014926217103120",
    "utcnow_ts": "1547793189232768"
  }
}
[06:33:09.246] Task URL: https://ci.chromium.org/b/8924014926217103120
[06:33:09.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:2:0) after 1m0s
[06:34:09.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:2:0)
[06:34:09.269] Timer tick, asking Buildbucket for the build status
[06:34:09.326] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:09.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:3:0) after 1m0s
[06:35:09.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:3:0)
[06:35:09.347] Timer tick, asking Buildbucket for the build status
[06:35:09.401] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:09.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:4:0) after 1m0s
[06:36:09.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:4:0)
[06:36:09.423] Timer tick, asking Buildbucket for the build status
[06:36:09.475] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:09.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:5:0) after 1m0s
[06:37:09.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:5:0)
[06:37:09.493] Timer tick, asking Buildbucket for the build status
[06:37:09.535] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:09.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:6:0) after 1m0s
[06:38:09.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:6:0)
[06:38:09.552] Timer tick, asking Buildbucket for the build status
[06:38:09.658] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:09.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:7:0) after 1m0s
[06:39:09.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:7:0)
[06:39:09.677] Timer tick, asking Buildbucket for the build status
[06:39:09.729] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:09.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:8:0) after 1m0s
[06:40:09.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:8:0)
[06:40:09.758] Timer tick, asking Buildbucket for the build status
[06:40:09.793] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:09.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:9:0) after 1m0s
[06:41:09.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:9:0)
[06:41:09.834] Timer tick, asking Buildbucket for the build status
[06:41:09.921] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:09.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:10:0) after 1m0s
[06:42:09.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:10:0)
[06:42:09.951] Timer tick, asking Buildbucket for the build status
[06:42:10.031] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:10.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:11:0) after 1m0s
[06:43:10.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:11:0)
[06:43:10.112] Timer tick, asking Buildbucket for the build status
[06:43:10.182] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:10.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:12:0) after 1m0s
[06:44:10.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:12:0)
[06:44:10.215] Timer tick, asking Buildbucket for the build status
[06:44:10.261] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:10.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:13:0) after 1m0s
[06:45:10.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:13:0)
[06:45:10.285] Timer tick, asking Buildbucket for the build status
[06:45:10.361] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:10.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:14:0) after 1m0s
[06:46:10.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:14:0)
[06:46:10.406] Timer tick, asking Buildbucket for the build status
[06:46:10.502] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:10.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:15:0) after 1m0s
[06:47:10.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:15:0)
[06:47:10.521] Timer tick, asking Buildbucket for the build status
[06:47:10.572] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:10.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:16:0) after 1m0s
[06:48:10.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:16:0)
[06:48:10.590] Timer tick, asking Buildbucket for the build status
[06:48:10.636] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:10.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:17:0) after 1m0s
[06:49:10.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:17:0)
[06:49:10.657] Timer tick, asking Buildbucket for the build status
[06:49:10.775] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:10.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:18:0) after 1m0s
[06:50:10.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:18:0)
[06:50:10.794] Timer tick, asking Buildbucket for the build status
[06:50:10.863] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:10.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:19:0) after 1m0s
[06:51:10.883] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:19:0)
[06:51:10.883] Timer tick, asking Buildbucket for the build status
[06:51:10.919] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:10.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:20:0) after 1m0s
[06:52:10.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:20:0)
[06:52:10.937] Timer tick, asking Buildbucket for the build status
[06:52:10.985] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:10.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:21:0) after 1m0s
[06:53:11.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:21:0)
[06:53:11.003] Timer tick, asking Buildbucket for the build status
[06:53:11.069] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:11.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:22:0) after 1m0s
[06:54:11.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:22:0)
[06:54:11.094] Timer tick, asking Buildbucket for the build status
[06:54:11.146] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:11.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:23:0) after 1m0s
[06:55:11.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:23:0)
[06:55:11.176] Timer tick, asking Buildbucket for the build status
[06:55:11.353] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:11.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:24:0) after 1m0s
[06:56:11.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:24:0)
[06:56:11.371] Timer tick, asking Buildbucket for the build status
[06:56:11.449] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:11.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:25:0) after 1m0s
[06:57:11.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:25:0)
[06:57:11.495] Timer tick, asking Buildbucket for the build status
[06:57:11.537] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:11.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:26:0) after 1m0s
[06:58:11.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:26:0)
[06:58:11.556] Timer tick, asking Buildbucket for the build status
[06:58:11.610] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:11.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:27:0) after 1m0s
[06:59:11.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:27:0)
[06:59:11.762] Timer tick, asking Buildbucket for the build status
[06:59:11.855] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:11.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:28:0) after 1m0s
[07:00:11.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:28:0)
[07:00:11.879] Timer tick, asking Buildbucket for the build status
[07:00:12.036] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:12.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:29:0) after 1m0s
[07:01:12.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:29:0)
[07:01:12.076] Timer tick, asking Buildbucket for the build status
[07:01:12.124] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:12.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:30:0) after 1m0s
[07:02:12.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:30:0)
[07:02:12.155] Timer tick, asking Buildbucket for the build status
[07:02:12.307] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:12.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:31:0) after 1m0s
[07:03:12.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:31:0)
[07:03:12.331] Timer tick, asking Buildbucket for the build status
[07:03:12.381] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:12.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:32:0) after 1m0s
[07:04:12.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:32:0)
[07:04:12.398] Timer tick, asking Buildbucket for the build status
[07:04:12.512] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:12.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:33:0) after 1m0s
[07:05:12.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:33:0)
[07:05:12.535] Timer tick, asking Buildbucket for the build status
[07:05:12.665] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:12.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:34:0) after 1m0s
[07:06:12.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:34:0)
[07:06:12.701] Timer tick, asking Buildbucket for the build status
[07:06:12.755] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:12.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:35:0) after 1m0s
[07:07:12.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:35:0)
[07:07:12.778] Timer tick, asking Buildbucket for the build status
[07:07:12.882] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:12.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:36:0) after 1m0s
[07:08:12.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:36:0)
[07:08:12.960] Timer tick, asking Buildbucket for the build status
[07:08:13.065] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:13.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:37:0) after 1m0s
[07:09:13.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:37:0)
[07:09:13.086] Timer tick, asking Buildbucket for the build status
[07:09:13.329] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:13.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:38:0) after 1m0s
[07:10:13.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:38:0)
[07:10:13.346] Timer tick, asking Buildbucket for the build status
[07:10:13.463] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:13.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:39:0) after 1m0s
[07:11:13.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:39:0)
[07:11:13.493] Timer tick, asking Buildbucket for the build status
[07:11:13.536] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:13.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:40:0) after 1m0s
[07:12:13.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:40:0)
[07:12:13.555] Timer tick, asking Buildbucket for the build status
[07:12:13.888] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:13.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:41:0) after 1m0s
[07:13:13.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:41:0)
[07:13:13.905] Timer tick, asking Buildbucket for the build status
[07:13:13.937] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:13.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:42:0) after 1m0s
[07:14:14.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:42:0)
[07:14:14.023] Timer tick, asking Buildbucket for the build status
[07:14:14.065] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:14.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:43:0) after 1m0s
[07:15:14.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:43:0)
[07:15:14.088] Timer tick, asking Buildbucket for the build status
[07:15:14.216] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:14.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:44:0) after 1m0s
[07:16:14.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:44:0)
[07:16:14.296] Timer tick, asking Buildbucket for the build status
[07:16:14.393] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:14.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:45:0) after 1m0s
[07:17:14.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:45:0)
[07:17:14.414] Timer tick, asking Buildbucket for the build status
[07:17:14.485] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:14.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:46:0) after 1m0s
[07:18:14.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:46:0)
[07:18:14.537] Timer tick, asking Buildbucket for the build status
[07:18:14.667] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:14.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:47:0) after 1m0s
[07:19:14.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:47:0)
[07:19:14.690] Timer tick, asking Buildbucket for the build status
[07:19:14.737] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:14.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:48:0) after 1m0s
[07:20:14.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:48:0)
[07:20:14.772] Timer tick, asking Buildbucket for the build status
[07:20:14.833] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:14.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:49:0) after 1m0s
[07:21:14.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:49:0)
[07:21:14.851] Timer tick, asking Buildbucket for the build status
[07:21:14.884] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:14.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:50:0) after 1m0s
[07:22:14.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:50:0)
[07:22:14.907] Timer tick, asking Buildbucket for the build status
[07:22:14.967] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:14.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:51:0) after 1m0s
[07:23:14.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:51:0)
[07:23:14.984] Timer tick, asking Buildbucket for the build status
[07:23:15.034] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:15.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:52:0) after 1m0s
[07:24:15.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:52:0)
[07:24:15.072] Timer tick, asking Buildbucket for the build status
[07:24:15.110] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:15.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:53:0) after 1m0s
[07:25:15.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:53:0)
[07:25:15.208] Timer tick, asking Buildbucket for the build status
[07:25:15.267] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:15.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:54:0) after 1m0s
[07:26:15.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:54:0)
[07:26:15.290] Timer tick, asking Buildbucket for the build status
[07:26:15.331] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:15.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:55:0) after 1m0s
[07:27:15.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:55:0)
[07:27:15.364] Timer tick, asking Buildbucket for the build status
[07:27:15.415] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:15.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:56:0) after 1m0s
[07:28:15.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:56:0)
[07:28:15.440] Timer tick, asking Buildbucket for the build status
[07:28:15.528] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:15.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:57:0) after 1m0s
[07:29:15.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:57:0)
[07:29:15.592] Timer tick, asking Buildbucket for the build status
[07:29:15.721] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:15.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:58:0) after 1m0s
[07:30:15.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:58:0)
[07:30:15.741] Timer tick, asking Buildbucket for the build status
[07:30:15.778] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:15.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:59:0) after 1m0s
[07:31:15.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:59:0)
[07:31:15.814] Timer tick, asking Buildbucket for the build status
[07:31:16.063] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:16.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:60:0) after 1m0s
[07:32:16.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:60:0)
[07:32:16.169] Timer tick, asking Buildbucket for the build status
[07:32:16.286] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:16.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:61:0) after 1m0s
[07:33:16.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:61:0)
[07:33:16.327] Timer tick, asking Buildbucket for the build status
[07:33:16.471] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:16.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:62:0) after 1m0s
[07:34:16.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:62:0)
[07:34:16.491] Timer tick, asking Buildbucket for the build status
[07:34:16.527] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:16.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:63:0) after 1m0s
[07:35:16.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:63:0)
[07:35:16.548] Timer tick, asking Buildbucket for the build status
[07:35:16.598] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:16.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:64:0) after 1m0s
[07:36:16.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:64:0)
[07:36:16.623] Timer tick, asking Buildbucket for the build status
[07:36:16.748] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:16.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:65:0) after 1m0s
[07:37:16.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:65:0)
[07:37:16.776] Timer tick, asking Buildbucket for the build status
[07:37:16.839] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:16.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:66:0) after 1m0s
[07:38:16.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:66:0)
[07:38:16.855] Timer tick, asking Buildbucket for the build status
[07:38:16.943] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:16.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:67:0) after 1m0s
[07:39:16.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:67:0)
[07:39:16.962] Timer tick, asking Buildbucket for the build status
[07:39:17.017] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:17.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:68:0) after 1m0s
[07:40:17.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:68:0)
[07:40:17.036] Timer tick, asking Buildbucket for the build status
[07:40:17.094] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:17.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:69:0) after 1m0s
[07:41:17.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:69:0)
[07:41:17.115] Timer tick, asking Buildbucket for the build status
[07:41:17.228] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:17.228] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:70:0) after 1m0s
[07:42:17.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:70:0)
[07:42:17.253] Timer tick, asking Buildbucket for the build status
[07:42:17.368] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:17.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:71:0) after 1m0s
[07:43:17.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:71:0)
[07:43:17.389] Timer tick, asking Buildbucket for the build status
[07:43:17.428] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:17.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:72:0) after 1m0s
[07:44:17.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:72:0)
[07:44:17.464] Timer tick, asking Buildbucket for the build status
[07:44:17.741] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:17.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:73:0) after 1m0s
[07:45:17.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:73:0)
[07:45:17.851] Timer tick, asking Buildbucket for the build status
[07:45:17.908] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:17.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:74:0) after 1m0s
[07:46:17.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:74:0)
[07:46:17.929] Timer tick, asking Buildbucket for the build status
[07:46:17.987] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:17.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:75:0) after 1m0s
[07:47:18.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:75:0)
[07:47:18.009] Timer tick, asking Buildbucket for the build status
[07:47:18.127] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:18.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:76:0) after 1m0s
[07:48:18.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:76:0)
[07:48:18.149] Timer tick, asking Buildbucket for the build status
[07:48:18.285] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:18.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:77:0) after 1m0s
[07:49:18.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:77:0)
[07:49:18.314] Timer tick, asking Buildbucket for the build status
[07:49:18.430] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:18.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:78:0) after 1m0s
[07:50:18.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:78:0)
[07:50:18.454] Timer tick, asking Buildbucket for the build status
[07:50:18.507] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:18.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:79:0) after 1m0s
[07:51:18.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:79:0)
[07:51:18.528] Timer tick, asking Buildbucket for the build status
[07:51:18.562] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:18.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:80:0) after 1m0s
[07:52:18.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:80:0)
[07:52:18.583] Timer tick, asking Buildbucket for the build status
[07:52:18.637] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:18.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:81:0) after 1m0s
[07:53:18.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:81:0)
[07:53:18.656] Timer tick, asking Buildbucket for the build status
[07:53:18.752] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:18.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:82:0) after 1m0s
[07:54:18.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:82:0)
[07:54:18.810] Timer tick, asking Buildbucket for the build status
[07:54:18.953] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:18.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:83:0) after 1m0s
[07:55:18.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:83:0)
[07:55:18.975] Timer tick, asking Buildbucket for the build status
[07:55:19.112] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:19.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:84:0) after 1m0s
[07:56:19.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:84:0)
[07:56:19.147] Timer tick, asking Buildbucket for the build status
[07:56:19.406] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:19.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:85:0) after 1m0s
[07:57:19.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:85:0)
[07:57:19.432] Timer tick, asking Buildbucket for the build status
[07:57:19.464] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:19.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:86:0) after 1m0s
[07:58:19.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:86:0)
[07:58:19.528] Timer tick, asking Buildbucket for the build status
[07:58:19.829] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:19.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:87:0) after 1m0s
[07:59:19.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:87:0)
[07:59:19.847] Timer tick, asking Buildbucket for the build status
[07:59:19.908] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:19.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:88:0) after 1m0s
[08:00:19.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:88:0)
[08:00:19.928] Timer tick, asking Buildbucket for the build status
[08:00:20.068] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:20.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:89:0) after 1m0s
[08:01:20.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:89:0)
[08:01:20.089] Timer tick, asking Buildbucket for the build status
[08:01:20.140] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:20.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:90:0) after 1m0s
[08:02:20.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:90:0)
[08:02:20.185] Timer tick, asking Buildbucket for the build status
[08:02:20.347] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:20.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:91:0) after 1m0s
[08:03:20.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:91:0)
[08:03:20.367] Timer tick, asking Buildbucket for the build status
[08:03:20.402] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:20.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:92:0) after 1m0s
[08:04:20.421] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:92:0)
[08:04:20.421] Timer tick, asking Buildbucket for the build status
[08:04:20.486] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:20.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:93:0) after 1m0s
[08:05:20.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:93:0)
[08:05:20.509] Timer tick, asking Buildbucket for the build status
[08:05:20.554] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:20.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:94:0) after 1m0s
[08:06:20.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:94:0)
[08:06:20.597] Timer tick, asking Buildbucket for the build status
[08:06:20.648] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:20.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:95:0) after 1m0s
[08:07:20.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:95:0)
[08:07:20.669] Timer tick, asking Buildbucket for the build status
[08:07:20.754] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:20.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:96:0) after 1m0s
[08:08:20.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:96:0)
[08:08:20.771] Timer tick, asking Buildbucket for the build status
[08:08:20.807] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:20.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:97:0) after 1m0s
[08:09:20.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:97:0)
[08:09:20.828] Timer tick, asking Buildbucket for the build status
[08:09:20.924] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:20.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:98:0) after 1m0s
[08:10:20.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:98:0)
[08:10:20.945] Timer tick, asking Buildbucket for the build status
[08:10:20.988] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:20.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:99:0) after 1m0s
[08:11:21.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:99:0)
[08:11:21.053] Timer tick, asking Buildbucket for the build status
[08:11:21.111] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:21.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:100:0) after 1m0s
[08:12:21.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:100:0)
[08:12:21.132] Timer tick, asking Buildbucket for the build status
[08:12:21.189] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:21.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:101:0) after 1m0s
[08:13:21.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:101:0)
[08:13:21.212] Timer tick, asking Buildbucket for the build status
[08:13:21.306] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:21.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:102:0) after 1m0s
[08:14:21.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:102:0)
[08:14:21.329] Timer tick, asking Buildbucket for the build status
[08:14:21.470] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:21.470] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:103:0) after 1m0s
[08:15:21.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:103:0)
[08:15:21.491] Timer tick, asking Buildbucket for the build status
[08:15:21.524] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:21.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:104:0) after 1m0s
[08:16:21.549] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:104:0)
[08:16:21.549] Timer tick, asking Buildbucket for the build status
[08:16:21.586] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:21.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:105:0) after 1m0s
[08:17:21.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:105:0)
[08:17:21.622] Timer tick, asking Buildbucket for the build status
[08:17:21.674] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:21.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:106:0) after 1m0s
[08:18:21.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:106:0)
[08:18:21.767] Timer tick, asking Buildbucket for the build status
[08:18:21.815] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:21.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:107:0) after 1m0s
[08:19:21.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:107:0)
[08:19:21.836] Timer tick, asking Buildbucket for the build status
[08:19:21.917] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:21.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:108:0) after 1m0s
[08:20:21.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:108:0)
[08:20:21.945] Timer tick, asking Buildbucket for the build status
[08:20:22.014] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:22.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:109:0) after 1m0s
[08:21:22.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:109:0)
[08:21:22.038] Timer tick, asking Buildbucket for the build status
[08:21:22.212] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:22.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:110:0) after 1m0s
[08:22:22.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:110:0)
[08:22:22.245] Timer tick, asking Buildbucket for the build status
[08:22:22.594] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:22.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:111:0) after 1m0s
[08:23:22.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:111:0)
[08:23:22.628] Timer tick, asking Buildbucket for the build status
[08:23:22.850] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:22.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:112:0) after 1m0s
[08:24:22.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:112:0)
[08:24:22.873] Timer tick, asking Buildbucket for the build status
[08:24:22.910] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:22.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:113:0) after 1m0s
[08:25:22.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:113:0)
[08:25:22.950] Timer tick, asking Buildbucket for the build status
[08:25:23.008] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:23.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:114:0) after 1m0s
[08:26:23.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:114:0)
[08:26:23.049] Timer tick, asking Buildbucket for the build status
[08:26:23.087] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:23.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:115:0) after 1m0s
[08:27:23.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:115:0)
[08:27:23.113] Timer tick, asking Buildbucket for the build status
[08:27:23.167] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:23.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:116:0) after 1m0s
[08:28:23.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:116:0)
[08:28:23.252] Timer tick, asking Buildbucket for the build status
[08:28:23.335] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:23.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:117:0) after 1m0s
[08:29:23.359] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:117:0)
[08:29:23.359] Timer tick, asking Buildbucket for the build status
[08:29:23.392] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:23.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:118:0) after 1m0s
[08:30:23.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:118:0)
[08:30:23.508] Timer tick, asking Buildbucket for the build status
[08:30:23.563] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:23.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:119:0) after 1m0s
[08:31:23.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:119:0)
[08:31:23.585] Timer tick, asking Buildbucket for the build status
[08:31:23.692] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:23.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:120:0) after 1m0s
[08:32:23.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:120:0)
[08:32:23.798] Timer tick, asking Buildbucket for the build status
[08:32:23.930] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:23.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:121:0) after 1m0s
[08:33:23.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:121:0)
[08:33:23.948] Timer tick, asking Buildbucket for the build status
[08:33:24.056] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:24.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:122:0) after 1m0s
[08:34:24.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:122:0)
[08:34:24.084] Timer tick, asking Buildbucket for the build status
[08:34:24.153] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:24.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:123:0) after 1m0s
[08:35:24.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:123:0)
[08:35:24.187] Timer tick, asking Buildbucket for the build status
[08:35:24.220] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:24.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:124:0) after 1m0s
[08:36:24.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:124:0)
[08:36:24.240] Timer tick, asking Buildbucket for the build status
[08:36:24.350] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:24.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:125:0) after 1m0s
[08:37:24.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:125:0)
[08:37:24.378] Timer tick, asking Buildbucket for the build status
[08:37:24.479] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:24.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:126:0) after 1m0s
[08:38:24.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:126:0)
[08:38:24.504] Timer tick, asking Buildbucket for the build status
[08:38:24.549] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:24.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:127:0) after 1m0s
[08:39:24.571] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:127:0)
[08:39:24.571] Timer tick, asking Buildbucket for the build status
[08:39:24.835] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:24.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:128:0) after 1m0s
[08:40:24.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:128:0)
[08:40:24.859] Timer tick, asking Buildbucket for the build status
[08:40:24.956] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:24.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:129:0) after 1m0s
[08:41:24.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:129:0)
[08:41:24.979] Timer tick, asking Buildbucket for the build status
[08:41:25.046] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:25.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:130:0) after 1m0s
[08:42:25.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:130:0)
[08:42:25.074] Timer tick, asking Buildbucket for the build status
[08:42:25.126] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:25.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:131:0) after 1m0s
[08:43:25.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:131:0)
[08:43:25.157] Timer tick, asking Buildbucket for the build status
[08:43:25.201] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:25.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:132:0) after 1m0s
[08:44:25.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:132:0)
[08:44:25.268] Timer tick, asking Buildbucket for the build status
[08:44:25.340] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:25.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:133:0) after 1m0s
[08:45:25.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:133:0)
[08:45:25.388] Timer tick, asking Buildbucket for the build status
[08:45:25.428] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:25.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:134:0) after 1m0s
[08:46:25.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:134:0)
[08:46:25.457] Timer tick, asking Buildbucket for the build status
[08:46:25.548] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:25.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:135:0) after 1m0s
[08:47:25.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:135:0)
[08:47:25.570] Timer tick, asking Buildbucket for the build status
[08:47:25.623] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:25.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:136:0) after 1m0s
[08:48:25.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:136:0)
[08:48:25.646] Timer tick, asking Buildbucket for the build status
[08:48:25.703] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:25.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:137:0) after 1m0s
[08:49:25.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:137:0)
[08:49:25.725] Timer tick, asking Buildbucket for the build status
[08:49:25.799] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:25.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:138:0) after 1m0s
[08:50:25.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:138:0)
[08:50:25.832] Timer tick, asking Buildbucket for the build status
[08:50:25.868] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:25.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:139:0) after 1m0s
[08:51:25.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:139:0)
[08:51:25.892] Timer tick, asking Buildbucket for the build status
[08:51:25.953] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:25.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:140:0) after 1m0s
[08:52:25.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:140:0)
[08:52:25.983] Timer tick, asking Buildbucket for the build status
[08:52:26.083] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:26.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:141:0) after 1m0s
[08:53:26.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:141:0)
[08:53:26.114] Timer tick, asking Buildbucket for the build status
[08:53:26.180] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:26.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:142:0) after 1m0s
[08:54:26.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:142:0)
[08:54:26.202] Timer tick, asking Buildbucket for the build status
[08:54:26.277] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:26.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:143:0) after 1m0s
[08:55:26.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:143:0)
[08:55:26.297] Timer tick, asking Buildbucket for the build status
[08:55:26.426] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:26.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:144:0) after 1m0s
[08:56:26.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:144:0)
[08:56:26.448] Timer tick, asking Buildbucket for the build status
[08:56:26.562] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:26.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:145:0) after 1m0s
[08:57:26.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:145:0)
[08:57:26.602] Timer tick, asking Buildbucket for the build status
[08:57:26.726] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:26.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:146:0) after 1m0s
[08:58:26.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:146:0)
[08:58:26.774] Timer tick, asking Buildbucket for the build status
[08:58:26.822] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:26.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:147:0) after 1m0s
[08:59:26.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:147:0)
[08:59:26.845] Timer tick, asking Buildbucket for the build status
[08:59:26.946] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:26.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:148:0) after 1m0s
[09:00:26.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:148:0)
[09:00:26.969] Timer tick, asking Buildbucket for the build status
[09:00:27.012] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:27.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:149:0) after 1m0s
[09:01:27.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:149:0)
[09:01:27.069] Timer tick, asking Buildbucket for the build status
[09:01:27.379] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:27.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:150:0) after 1m0s
[09:02:27.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:150:0)
[09:02:27.411] Timer tick, asking Buildbucket for the build status
[09:02:27.487] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:27.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:151:0) after 1m0s
[09:03:27.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:151:0)
[09:03:27.538] Timer tick, asking Buildbucket for the build status
[09:03:27.635] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:27.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:152:0) after 1m0s
[09:04:27.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:152:0)
[09:04:27.657] Timer tick, asking Buildbucket for the build status
[09:04:27.710] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:27.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:153:0) after 1m0s
[09:05:27.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:153:0)
[09:05:27.735] Timer tick, asking Buildbucket for the build status
[09:05:27.857] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:27.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:154:0) after 1m0s
[09:06:27.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:154:0)
[09:06:27.882] Timer tick, asking Buildbucket for the build status
[09:06:27.930] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:27.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:155:0) after 1m0s
[09:07:27.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:155:0)
[09:07:27.972] Timer tick, asking Buildbucket for the build status
[09:07:28.071] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:28.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:156:0) after 1m0s
[09:08:28.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:156:0)
[09:08:28.096] Timer tick, asking Buildbucket for the build status
[09:08:28.194] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:28.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:157:0) after 1m0s
[09:09:28.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:157:0)
[09:09:28.225] Timer tick, asking Buildbucket for the build status
[09:09:28.259] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:28.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:158:0) after 1m0s
[09:10:28.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:158:0)
[09:10:28.348] Timer tick, asking Buildbucket for the build status
[09:10:28.410] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:28.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:159:0) after 1m0s
[09:11:28.486] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:159:0)
[09:11:28.486] Timer tick, asking Buildbucket for the build status
[09:11:28.630] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:28.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:160:0) after 1m0s
[09:12:28.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:160:0)
[09:12:28.651] Timer tick, asking Buildbucket for the build status
[09:12:28.759] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:28.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:161:0) after 1m0s
[09:13:28.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:161:0)
[09:13:28.795] Timer tick, asking Buildbucket for the build status
[09:13:28.873] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:28.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:162:0) after 1m0s
[09:14:28.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:162:0)
[09:14:28.907] Timer tick, asking Buildbucket for the build status
[09:14:28.940] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:28.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:163:0) after 1m0s
[09:15:28.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:163:0)
[09:15:28.972] Timer tick, asking Buildbucket for the build status
[09:15:29.053] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:29.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:164:0) after 1m0s
[09:16:29.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:164:0)
[09:16:29.108] Timer tick, asking Buildbucket for the build status
[09:16:29.143] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:29.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:165:0) after 1m0s
[09:17:29.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:165:0)
[09:17:29.175] Timer tick, asking Buildbucket for the build status
[09:17:29.208] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:29.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:166:0) after 1m0s
[09:18:29.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:166:0)
[09:18:29.285] Timer tick, asking Buildbucket for the build status
[09:18:29.362] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:29.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:167:0) after 1m0s
[09:19:29.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:167:0)
[09:19:29.383] Timer tick, asking Buildbucket for the build status
[09:19:29.447] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:29.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:168:0) after 1m0s
[09:20:29.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:168:0)
[09:20:29.479] Timer tick, asking Buildbucket for the build status
[09:20:29.660] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:29.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:169:0) after 1m0s
[09:21:29.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:169:0)
[09:21:29.682] Timer tick, asking Buildbucket for the build status
[09:21:29.842] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:29.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:170:0) after 1m0s
[09:22:29.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:170:0)
[09:22:29.865] Timer tick, asking Buildbucket for the build status
[09:22:29.927] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:29.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:171:0) after 1m0s
[09:23:29.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:171:0)
[09:23:29.951] Timer tick, asking Buildbucket for the build status
[09:23:30.022] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:30.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:172:0) after 1m0s
[09:24:30.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:172:0)
[09:24:30.043] Timer tick, asking Buildbucket for the build status
[09:24:30.135] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:30.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:173:0) after 1m0s
[09:25:30.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:173:0)
[09:25:30.164] Timer tick, asking Buildbucket for the build status
[09:25:30.202] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:30.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:174:0) after 1m0s
[09:26:30.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:174:0)
[09:26:30.230] Timer tick, asking Buildbucket for the build status
[09:26:30.306] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:30.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:175:0) after 1m0s
[09:27:30.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:175:0)
[09:27:30.332] Timer tick, asking Buildbucket for the build status
[09:27:30.384] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:30.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:176:0) after 1m0s
[09:28:30.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:176:0)
[09:28:30.414] Timer tick, asking Buildbucket for the build status
[09:28:30.467] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:30.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:177:0) after 1m0s
[09:29:30.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:177:0)
[09:29:30.500] Timer tick, asking Buildbucket for the build status
[09:29:30.535] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:30.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:178:0) after 1m0s
[09:30:30.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:178:0)
[09:30:30.567] Timer tick, asking Buildbucket for the build status
[09:30:30.627] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:30.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:179:0) after 1m0s
[09:31:30.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:179:0)
[09:31:30.649] Timer tick, asking Buildbucket for the build status
[09:31:30.705] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:30.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:180:0) after 1m0s
[09:32:30.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:180:0)
[09:32:30.730] Timer tick, asking Buildbucket for the build status
[09:32:30.767] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:30.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:181:0) after 1m0s
[09:33:30.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:181:0)
[09:33:30.803] Timer tick, asking Buildbucket for the build status
[09:33:30.866] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:30.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:182:0) after 1m0s
[09:34:30.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:182:0)
[09:34:30.943] Timer tick, asking Buildbucket for the build status
[09:34:30.973] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:30.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:183:0) after 1m0s
[09:35:31.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:183:0)
[09:35:31.001] Timer tick, asking Buildbucket for the build status
[09:35:31.063] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:31.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:184:0) after 1m0s
[09:36:31.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:184:0)
[09:36:31.087] Timer tick, asking Buildbucket for the build status
[09:36:31.581] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:31.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:185:0) after 1m0s
[09:37:31.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:185:0)
[09:37:31.603] Timer tick, asking Buildbucket for the build status
[09:37:31.667] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:31.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:186:0) after 1m0s
[09:38:31.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:186:0)
[09:38:31.740] Timer tick, asking Buildbucket for the build status
[09:38:31.784] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:31.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:187:0) after 1m0s
[09:39:31.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:187:0)
[09:39:31.927] Timer tick, asking Buildbucket for the build status
[09:39:32.105] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:32.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:188:0) after 1m0s
[09:40:32.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:188:0)
[09:40:32.148] Timer tick, asking Buildbucket for the build status
[09:40:32.181] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:32.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:189:0) after 1m0s
[09:41:32.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:189:0)
[09:41:32.203] Timer tick, asking Buildbucket for the build status
[09:41:32.259] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:32.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:190:0) after 1m0s
[09:42:32.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:190:0)
[09:42:32.292] Timer tick, asking Buildbucket for the build status
[09:42:32.349] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:32.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:191:0) after 1m0s
[09:43:32.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:191:0)
[09:43:32.409] Timer tick, asking Buildbucket for the build status
[09:43:32.522] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:32.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:192:0) after 1m0s
[09:44:32.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:192:0)
[09:44:32.542] Timer tick, asking Buildbucket for the build status
[09:44:32.636] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:32.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:193:0) after 1m0s
[09:45:32.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:193:0)
[09:45:32.806] Timer tick, asking Buildbucket for the build status
[09:45:32.868] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:32.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:194:0) after 1m0s
[09:46:32.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:194:0)
[09:46:32.893] Timer tick, asking Buildbucket for the build status
[09:46:32.936] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:32.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:195:0) after 1m0s
[09:47:32.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:195:0)
[09:47:32.960] Timer tick, asking Buildbucket for the build status
[09:47:33.063] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:33.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:196:0) after 1m0s
[09:48:33.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:196:0)
[09:48:33.087] Timer tick, asking Buildbucket for the build status
[09:48:33.122] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:33.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:197:0) after 1m0s
[09:49:33.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:197:0)
[09:49:33.147] Timer tick, asking Buildbucket for the build status
[09:49:33.252] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:33.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:198:0) after 1m0s
[09:50:33.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:198:0)
[09:50:33.281] Timer tick, asking Buildbucket for the build status
[09:50:33.360] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:33.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:199:0) after 1m0s
[09:51:33.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:199:0)
[09:51:33.397] Timer tick, asking Buildbucket for the build status
[09:51:33.456] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:33.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:200:0) after 1m0s
[09:52:33.480] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:200:0)
[09:52:33.480] Timer tick, asking Buildbucket for the build status
[09:52:33.535] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:33.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:201:0) after 1m0s
[09:53:33.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:201:0)
[09:53:33.703] Timer tick, asking Buildbucket for the build status
[09:53:33.778] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:33.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:202:0) after 1m0s
[09:54:33.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:202:0)
[09:54:33.800] Timer tick, asking Buildbucket for the build status
[09:54:33.938] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:33.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:203:0) after 1m0s
[09:55:33.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:203:0)
[09:55:33.972] Timer tick, asking Buildbucket for the build status
[09:55:34.060] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:34.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:204:0) after 1m0s
[09:56:34.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:204:0)
[09:56:34.088] Timer tick, asking Buildbucket for the build status
[09:56:34.123] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:34.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:205:0) after 1m0s
[09:57:34.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:205:0)
[09:57:34.164] Timer tick, asking Buildbucket for the build status
[09:57:34.208] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:34.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:206:0) after 1m0s
[09:58:34.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:206:0)
[09:58:34.232] Timer tick, asking Buildbucket for the build status
[09:58:34.289] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:34.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:207:0) after 1m0s
[09:59:34.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:207:0)
[09:59:34.311] Timer tick, asking Buildbucket for the build status
[09:59:34.361] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:34.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:208:0) after 1m0s
[10:00:34.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:208:0)
[10:00:34.386] Timer tick, asking Buildbucket for the build status
[10:00:34.421] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:34.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:209:0) after 1m0s
[10:01:34.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:209:0)
[10:01:34.452] Timer tick, asking Buildbucket for the build status
[10:01:34.706] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:34.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:210:0) after 1m0s
[10:02:34.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:210:0)
[10:02:34.728] Timer tick, asking Buildbucket for the build status
[10:02:34.768] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:34.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:211:0) after 1m0s
[10:03:34.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:211:0)
[10:03:34.793] Timer tick, asking Buildbucket for the build status
[10:03:34.843] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:34.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:212:0) after 1m0s
[10:04:34.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:212:0)
[10:04:34.869] Timer tick, asking Buildbucket for the build status
[10:04:34.950] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:34.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:213:0) after 1m0s
[10:05:34.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:213:0)
[10:05:34.978] Timer tick, asking Buildbucket for the build status
[10:05:35.028] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:35.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:214:0) after 1m0s
[10:06:35.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:214:0)
[10:06:35.069] Timer tick, asking Buildbucket for the build status
[10:06:35.157] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:35.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:215:0) after 1m0s
[10:07:35.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:215:0)
[10:07:35.185] Timer tick, asking Buildbucket for the build status
[10:07:35.219] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:35.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:216:0) after 1m0s
[10:08:35.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:216:0)
[10:08:35.243] Timer tick, asking Buildbucket for the build status
[10:08:35.302] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:35.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:217:0) after 1m0s
[10:09:35.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:217:0)
[10:09:35.328] Timer tick, asking Buildbucket for the build status
[10:09:35.369] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:35.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:218:0) after 1m0s
[10:10:35.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:218:0)
[10:10:35.400] Timer tick, asking Buildbucket for the build status
[10:10:35.456] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:35.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:219:0) after 1m0s
[10:11:35.480] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:219:0)
[10:11:35.480] Timer tick, asking Buildbucket for the build status
[10:11:35.534] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:35.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:220:0) after 1m0s
[10:12:35.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:220:0)
[10:12:35.555] Timer tick, asking Buildbucket for the build status
[10:12:35.670] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:35.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:221:0) after 1m0s
[10:13:35.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:221:0)
[10:13:35.692] Timer tick, asking Buildbucket for the build status
[10:13:35.750] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:35.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:222:0) after 1m0s
[10:14:35.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:222:0)
[10:14:35.776] Timer tick, asking Buildbucket for the build status
[10:14:35.861] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:35.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:223:0) after 1m0s
[10:15:35.888] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:223:0)
[10:15:35.888] Timer tick, asking Buildbucket for the build status
[10:15:36.011] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:36.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:224:0) after 1m0s
[10:16:36.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:224:0)
[10:16:36.034] Timer tick, asking Buildbucket for the build status
[10:16:36.090] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:36.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:225:0) after 1m0s
[10:17:36.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:225:0)
[10:17:36.185] Timer tick, asking Buildbucket for the build status
[10:17:36.270] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:36.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:226:0) after 1m0s
[10:18:36.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:226:0)
[10:18:36.295] Timer tick, asking Buildbucket for the build status
[10:18:36.349] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:36.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:227:0) after 1m0s
[10:19:36.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:227:0)
[10:19:36.377] Timer tick, asking Buildbucket for the build status
[10:19:36.442] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:36.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:228:0) after 1m0s
[10:20:36.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:228:0)
[10:20:36.481] Timer tick, asking Buildbucket for the build status
[10:20:36.557] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:36.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:229:0) after 1m0s
[10:21:36.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:229:0)
[10:21:36.585] Timer tick, asking Buildbucket for the build status
[10:21:36.649] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:36.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:230:0) after 1m0s
[10:22:36.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:230:0)
[10:22:36.696] Timer tick, asking Buildbucket for the build status
[10:22:36.733] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:36.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:231:0) after 1m0s
[10:23:36.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:231:0)
[10:23:36.761] Timer tick, asking Buildbucket for the build status
[10:23:36.814] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:36.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:232:0) after 1m0s
[10:24:36.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:232:0)
[10:24:36.841] Timer tick, asking Buildbucket for the build status
[10:24:36.929] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:36.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:233:0) after 1m0s
[10:25:36.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:233:0)
[10:25:36.983] Timer tick, asking Buildbucket for the build status
[10:25:37.037] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:25:37.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:234:0) after 1m0s
[10:26:37.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:234:0)
[10:26:37.063] Timer tick, asking Buildbucket for the build status
[10:26:37.101] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:26:37.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:235:0) after 1m0s
[10:27:37.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:235:0)
[10:27:37.127] Timer tick, asking Buildbucket for the build status
[10:27:37.181] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:27:37.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:236:0) after 1m0s
[10:28:37.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:236:0)
[10:28:37.207] Timer tick, asking Buildbucket for the build status
[10:28:37.243] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:28:37.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:237:0) after 1m0s
[10:29:37.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:237:0)
[10:29:37.348] Timer tick, asking Buildbucket for the build status
[10:29:37.465] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:29:37.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:238:0) after 1m0s
[10:30:37.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:238:0)
[10:30:37.489] Timer tick, asking Buildbucket for the build status
[10:30:37.532] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:30:37.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:239:0) after 1m0s
[10:31:37.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:239:0)
[10:31:37.563] Timer tick, asking Buildbucket for the build status
[10:31:37.624] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:31:37.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:240:0) after 1m0s
[10:32:37.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:240:0)
[10:32:37.649] Timer tick, asking Buildbucket for the build status
[10:32:37.679] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:32:37.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:241:0) after 1m0s
[10:33:37.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:241:0)
[10:33:37.702] Timer tick, asking Buildbucket for the build status
[10:33:37.770] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:33:37.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:242:0) after 1m0s
[10:34:37.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:242:0)
[10:34:37.809] Timer tick, asking Buildbucket for the build status
[10:34:37.839] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:37.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:243:0) after 1m0s
[10:35:37.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:243:0)
[10:35:37.882] Timer tick, asking Buildbucket for the build status
[10:35:37.962] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:37.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:244:0) after 1m0s
[10:36:37.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:244:0)
[10:36:37.989] Timer tick, asking Buildbucket for the build status
[10:36:38.024] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:38.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:245:0) after 1m0s
[10:37:38.050] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:245:0)
[10:37:38.050] Timer tick, asking Buildbucket for the build status
[10:37:38.118] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:38.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:246:0) after 1m0s
[10:38:38.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:246:0)
[10:38:38.142] Timer tick, asking Buildbucket for the build status
[10:38:38.357] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:38:38.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:247:0) after 1m0s
[10:39:38.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:247:0)
[10:39:38.381] Timer tick, asking Buildbucket for the build status
[10:39:38.456] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:38.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:248:0) after 1m0s
[10:40:38.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:248:0)
[10:40:38.544] Timer tick, asking Buildbucket for the build status
[10:40:38.596] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:38.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:249:0) after 1m0s
[10:41:38.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:249:0)
[10:41:38.634] Timer tick, asking Buildbucket for the build status
[10:41:38.782] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:38.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:250:0) after 1m0s
[10:42:38.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:250:0)
[10:42:38.805] Timer tick, asking Buildbucket for the build status
[10:42:38.853] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:38.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:251:0) after 1m0s
[10:43:38.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:251:0)
[10:43:38.886] Timer tick, asking Buildbucket for the build status
[10:43:38.920] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:43:38.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:252:0) after 1m0s
[10:44:38.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:252:0)
[10:44:38.942] Timer tick, asking Buildbucket for the build status
[10:44:39.229] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:44:39.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:253:0) after 1m0s
[10:45:39.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:253:0)
[10:45:39.258] Timer tick, asking Buildbucket for the build status
[10:45:39.290] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:45:39.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:254:0) after 1m0s
[10:46:39.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:254:0)
[10:46:39.312] Timer tick, asking Buildbucket for the build status
[10:46:39.610] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:46:39.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:255:0) after 1m0s
[10:47:39.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:255:0)
[10:47:39.649] Timer tick, asking Buildbucket for the build status
[10:47:39.704] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:47:39.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:256:0) after 1m0s
[10:48:39.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:256:0)
[10:48:39.729] Timer tick, asking Buildbucket for the build status
[10:48:39.764] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:48:39.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:257:0) after 1m0s
[10:49:39.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:257:0)
[10:49:39.795] Timer tick, asking Buildbucket for the build status
[10:49:39.855] Build 8924014926217103120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:39.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:258:0) after 1m0s
[10:50:30.784] Received PubSub notification, asking Buildbucket for the build status
[10:50:31.094] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:39.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:258:0)
[10:50:39.877] Timer tick, asking Buildbucket for the build status
[10:50:39.959] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:39.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:260:0) after 1m0s
[10:51:39.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:260:0)
[10:51:39.987] Timer tick, asking Buildbucket for the build status
[10:51:40.077] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:40.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:261:0) after 1m0s
[10:52:40.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:261:0)
[10:52:40.108] Timer tick, asking Buildbucket for the build status
[10:52:40.161] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:40.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:262:0) after 1m0s
[10:53:40.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:262:0)
[10:53:40.195] Timer tick, asking Buildbucket for the build status
[10:53:40.305] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:40.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:263:0) after 1m0s
[10:54:40.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:263:0)
[10:54:40.326] Timer tick, asking Buildbucket for the build status
[10:54:40.464] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:40.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:264:0) after 1m0s
[10:55:40.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:264:0)
[10:55:40.511] Timer tick, asking Buildbucket for the build status
[10:55:40.655] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:40.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:265:0) after 1m0s
[10:56:40.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:265:0)
[10:56:40.690] Timer tick, asking Buildbucket for the build status
[10:56:40.836] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:40.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:266:0) after 1m0s
[10:57:40.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:266:0)
[10:57:40.863] Timer tick, asking Buildbucket for the build status
[10:57:40.921] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:40.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:267:0) after 1m0s
[10:58:40.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:267:0)
[10:58:40.945] Timer tick, asking Buildbucket for the build status
[10:58:41.062] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:41.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:268:0) after 1m0s
[10:59:41.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:268:0)
[10:59:41.096] Timer tick, asking Buildbucket for the build status
[10:59:41.131] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:41.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:269:0) after 1m0s
[11:00:41.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:269:0)
[11:00:41.155] Timer tick, asking Buildbucket for the build status
[11:00:41.215] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:41.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:270:0) after 1m0s
[11:01:41.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:270:0)
[11:01:41.256] Timer tick, asking Buildbucket for the build status
[11:01:41.299] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:41.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:271:0) after 1m0s
[11:02:41.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:271:0)
[11:02:41.325] Timer tick, asking Buildbucket for the build status
[11:02:41.413] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:41.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:272:0) after 1m0s
[11:03:41.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:272:0)
[11:03:41.437] Timer tick, asking Buildbucket for the build status
[11:03:41.639] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:41.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:273:0) after 1m0s
[11:04:41.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:273:0)
[11:04:41.663] Timer tick, asking Buildbucket for the build status
[11:04:41.696] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:41.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:274:0) after 1m0s
[11:05:41.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:274:0)
[11:05:41.720] Timer tick, asking Buildbucket for the build status
[11:05:41.765] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:41.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:275:0) after 1m0s
[11:06:41.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:275:0)
[11:06:41.812] Timer tick, asking Buildbucket for the build status
[11:06:42.009] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:42.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:276:0) after 1m0s
[11:07:42.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:276:0)
[11:07:42.035] Timer tick, asking Buildbucket for the build status
[11:07:42.097] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:42.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:277:0) after 1m0s
[11:08:42.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:277:0)
[11:08:42.165] Timer tick, asking Buildbucket for the build status
[11:08:42.245] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:42.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:278:0) after 1m0s
[11:09:42.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:278:0)
[11:09:42.280] Timer tick, asking Buildbucket for the build status
[11:09:42.359] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:42.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:279:0) after 1m0s
[11:10:42.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:279:0)
[11:10:42.415] Timer tick, asking Buildbucket for the build status
[11:10:42.476] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:42.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:280:0) after 1m0s
[11:11:42.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:280:0)
[11:11:42.502] Timer tick, asking Buildbucket for the build status
[11:11:42.573] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:42.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:281:0) after 1m0s
[11:12:42.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:281:0)
[11:12:42.599] Timer tick, asking Buildbucket for the build status
[11:12:42.671] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:42.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:282:0) after 1m0s
[11:13:42.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:282:0)
[11:13:42.695] Timer tick, asking Buildbucket for the build status
[11:13:42.779] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:42.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:283:0) after 1m0s
[11:14:42.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:283:0)
[11:14:42.804] Timer tick, asking Buildbucket for the build status
[11:14:42.894] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:42.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:284:0) after 1m0s
[11:15:42.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:284:0)
[11:15:42.923] Timer tick, asking Buildbucket for the build status
[11:15:43.050] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:43.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:285:0) after 1m0s
[11:16:43.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:285:0)
[11:16:43.087] Timer tick, asking Buildbucket for the build status
[11:16:43.164] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:43.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:286:0) after 1m0s
[11:17:43.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:286:0)
[11:17:43.197] Timer tick, asking Buildbucket for the build status
[11:17:43.267] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:43.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:287:0) after 1m0s
[11:18:43.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:287:0)
[11:18:43.293] Timer tick, asking Buildbucket for the build status
[11:18:43.366] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:43.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:288:0) after 1m0s
[11:19:43.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:288:0)
[11:19:43.392] Timer tick, asking Buildbucket for the build status
[11:19:43.456] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:43.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:289:0) after 1m0s
[11:20:43.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:289:0)
[11:20:43.493] Timer tick, asking Buildbucket for the build status
[11:20:43.581] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:43.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:290:0) after 1m0s
[11:21:43.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:290:0)
[11:21:43.635] Timer tick, asking Buildbucket for the build status
[11:21:43.713] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:43.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:291:0) after 1m0s
[11:22:43.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:291:0)
[11:22:43.739] Timer tick, asking Buildbucket for the build status
[11:22:43.812] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:43.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:292:0) after 1m0s
[11:23:43.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:292:0)
[11:23:43.840] Timer tick, asking Buildbucket for the build status
[11:23:43.930] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:43.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:293:0) after 1m0s
[11:24:43.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:293:0)
[11:24:43.957] Timer tick, asking Buildbucket for the build status
[11:24:43.993] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:43.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:294:0) after 1m0s
[11:25:44.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:294:0)
[11:25:44.018] Timer tick, asking Buildbucket for the build status
[11:25:44.083] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:44.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:295:0) after 1m0s
[11:26:44.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:295:0)
[11:26:44.112] Timer tick, asking Buildbucket for the build status
[11:26:44.222] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:44.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:296:0) after 1m0s
[11:27:44.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:296:0)
[11:27:44.252] Timer tick, asking Buildbucket for the build status
[11:27:44.292] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:44.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:297:0) after 1m0s
[11:28:44.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:297:0)
[11:28:44.316] Timer tick, asking Buildbucket for the build status
[11:28:44.436] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:44.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:298:0) after 1m0s
[11:29:44.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:298:0)
[11:29:44.460] Timer tick, asking Buildbucket for the build status
[11:29:44.560] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:44.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:299:0) after 1m0s
[11:30:44.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:299:0)
[11:30:44.664] Timer tick, asking Buildbucket for the build status
[11:30:44.780] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:44.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:300:0) after 1m0s
[11:31:44.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:300:0)
[11:31:44.805] Timer tick, asking Buildbucket for the build status
[11:31:44.845] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:44.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:301:0) after 1m0s
[11:32:44.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:301:0)
[11:32:44.876] Timer tick, asking Buildbucket for the build status
[11:32:44.961] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:44.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:302:0) after 1m0s
[11:33:44.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:302:0)
[11:33:44.984] Timer tick, asking Buildbucket for the build status
[11:33:45.029] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:45.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:303:0) after 1m0s
[11:34:45.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:303:0)
[11:34:45.059] Timer tick, asking Buildbucket for the build status
[11:34:45.209] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:45.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:304:0) after 1m0s
[11:35:45.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:304:0)
[11:35:45.236] Timer tick, asking Buildbucket for the build status
[11:35:45.319] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:45.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:305:0) after 1m0s
[11:36:45.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:305:0)
[11:36:45.343] Timer tick, asking Buildbucket for the build status
[11:36:45.425] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:45.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:306:0) after 1m0s
[11:37:45.450] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:306:0)
[11:37:45.450] Timer tick, asking Buildbucket for the build status
[11:37:45.547] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:45.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:307:0) after 1m0s
[11:38:45.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:307:0)
[11:38:45.572] Timer tick, asking Buildbucket for the build status
[11:38:45.642] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:45.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:308:0) after 1m0s
[11:39:45.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:308:0)
[11:39:45.666] Timer tick, asking Buildbucket for the build status
[11:39:45.703] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:45.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:309:0) after 1m0s
[11:40:45.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:309:0)
[11:40:45.739] Timer tick, asking Buildbucket for the build status
[11:40:45.800] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:45.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:310:0) after 1m0s
[11:41:45.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:310:0)
[11:41:45.844] Timer tick, asking Buildbucket for the build status
[11:41:45.911] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:45.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:311:0) after 1m0s
[11:42:45.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:311:0)
[11:42:45.937] Timer tick, asking Buildbucket for the build status
[11:42:46.007] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:46.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:312:0) after 1m0s
[11:43:46.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:312:0)
[11:43:46.078] Timer tick, asking Buildbucket for the build status
[11:43:46.148] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:46.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:313:0) after 1m0s
[11:44:46.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:313:0)
[11:44:46.182] Timer tick, asking Buildbucket for the build status
[11:44:46.253] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:46.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:314:0) after 1m0s
[11:45:46.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:314:0)
[11:45:46.277] Timer tick, asking Buildbucket for the build status
[11:45:46.341] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:46.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:315:0) after 1m0s
[11:46:46.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:315:0)
[11:46:46.379] Timer tick, asking Buildbucket for the build status
[11:46:46.430] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:46.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:316:0) after 1m0s
[11:47:46.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:316:0)
[11:47:46.477] Timer tick, asking Buildbucket for the build status
[11:47:46.706] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:46.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:317:0) after 1m0s
[11:48:46.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:317:0)
[11:48:46.733] Timer tick, asking Buildbucket for the build status
[11:48:46.807] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:46.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:318:0) after 1m0s
[11:49:46.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:318:0)
[11:49:46.832] Timer tick, asking Buildbucket for the build status
[11:49:46.894] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:46.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:319:0) after 1m0s
[11:50:46.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:319:0)
[11:50:46.921] Timer tick, asking Buildbucket for the build status
[11:50:46.985] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:46.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:320:0) after 1m0s
[11:51:47.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:320:0)
[11:51:47.016] Timer tick, asking Buildbucket for the build status
[11:51:47.085] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:47.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:321:0) after 1m0s
[11:52:47.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:321:0)
[11:52:47.127] Timer tick, asking Buildbucket for the build status
[11:52:47.309] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:47.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:322:0) after 1m0s
[11:53:47.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:322:0)
[11:53:47.334] Timer tick, asking Buildbucket for the build status
[11:53:47.382] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:47.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:323:0) after 1m0s
[11:54:47.421] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:323:0)
[11:54:47.421] Timer tick, asking Buildbucket for the build status
[11:54:47.502] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:47.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:324:0) after 1m0s
[11:55:47.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:324:0)
[11:55:47.527] Timer tick, asking Buildbucket for the build status
[11:55:47.639] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:47.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:325:0) after 1m0s
[11:56:47.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:325:0)
[11:56:47.708] Timer tick, asking Buildbucket for the build status
[11:56:47.854] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:47.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:326:0) after 1m0s
[11:57:47.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:326:0)
[11:57:47.891] Timer tick, asking Buildbucket for the build status
[11:57:47.980] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:47.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:327:0) after 1m0s
[11:58:48.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:327:0)
[11:58:48.006] Timer tick, asking Buildbucket for the build status
[11:58:48.052] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:48.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:328:0) after 1m0s
[11:59:48.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:328:0)
[11:59:48.079] Timer tick, asking Buildbucket for the build status
[11:59:48.206] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:48.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:329:0) after 1m0s
[12:00:48.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:329:0)
[12:00:48.243] Timer tick, asking Buildbucket for the build status
[12:00:48.344] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:48.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:330:0) after 1m0s
[12:01:48.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:330:0)
[12:01:48.380] Timer tick, asking Buildbucket for the build status
[12:01:48.425] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:48.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:331:0) after 1m0s
[12:02:48.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:331:0)
[12:02:48.462] Timer tick, asking Buildbucket for the build status
[12:02:48.540] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:48.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:332:0) after 1m0s
[12:03:48.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:332:0)
[12:03:48.565] Timer tick, asking Buildbucket for the build status
[12:03:48.629] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:48.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:333:0) after 1m0s
[12:04:48.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:333:0)
[12:04:48.654] Timer tick, asking Buildbucket for the build status
[12:04:48.828] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:48.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:334:0) after 1m0s
[12:05:48.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:334:0)
[12:05:48.855] Timer tick, asking Buildbucket for the build status
[12:05:48.952] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:48.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:335:0) after 1m0s
[12:06:48.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:335:0)
[12:06:48.980] Timer tick, asking Buildbucket for the build status
[12:06:49.145] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:49.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:336:0) after 1m0s
[12:07:49.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:336:0)
[12:07:49.201] Timer tick, asking Buildbucket for the build status
[12:07:49.346] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:49.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:337:0) after 1m0s
[12:08:49.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:337:0)
[12:08:49.373] Timer tick, asking Buildbucket for the build status
[12:08:49.448] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:49.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:338:0) after 1m0s
[12:09:49.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:338:0)
[12:09:49.535] Timer tick, asking Buildbucket for the build status
[12:09:49.663] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:49.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:339:0) after 1m0s
[12:10:49.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:339:0)
[12:10:49.691] Timer tick, asking Buildbucket for the build status
[12:10:49.801] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:49.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:340:0) after 1m0s
[12:11:49.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:340:0)
[12:11:49.830] Timer tick, asking Buildbucket for the build status
[12:11:50.056] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:50.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:341:0) after 1m0s
[12:12:50.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:341:0)
[12:12:50.094] Timer tick, asking Buildbucket for the build status
[12:12:50.258] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:50.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:342:0) after 1m0s
[12:13:50.283] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:342:0)
[12:13:50.283] Timer tick, asking Buildbucket for the build status
[12:13:50.359] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:50.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:343:0) after 1m0s
[12:14:50.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:343:0)
[12:14:50.390] Timer tick, asking Buildbucket for the build status
[12:14:50.462] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:50.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:344:0) after 1m0s
[12:15:50.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:344:0)
[12:15:50.492] Timer tick, asking Buildbucket for the build status
[12:15:50.563] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:50.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:345:0) after 1m0s
[12:16:50.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:345:0)
[12:16:50.626] Timer tick, asking Buildbucket for the build status
[12:16:50.745] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:50.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:346:0) after 1m0s
[12:17:50.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:346:0)
[12:17:50.771] Timer tick, asking Buildbucket for the build status
[12:17:50.959] Build 8924014926217103120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:50.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9089444988512989056:347:0) after 1m0s
[12:18:29.014] Received PubSub notification, asking Buildbucket for the build status
[12:18:29.050] Build 8924014926217103120: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:18:29.050] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1547813907012883",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547793187848335",
    "failure_reason": "BUILD_FAILURE",
    "id": "8924014926217103120",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-32 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-32 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-32 --opt O3f --opt O2b_sz\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4004-c99644c\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-kxnl\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 5112, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"got_revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547793187848335, \"tags\": [\"builder:linux-pnacl-x86_64-tests-x86_32\", \"buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089444988512989056\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8924014926217103120\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"84d6fbfa44f1de95e21107766fc35fa1f6ac0897\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-kxnl\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1547808629912742",
    "status": "COMPLETED",
    "status_changed_ts": "1547813908449232",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5112",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089444988512989056",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5112",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8924014926217103120",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/84d6fbfa44f1de95e21107766fc35fa1f6ac0897",
      "swarming_tag:caches:builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924014926217103120/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089444988512989056",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:d3b70e1553a043aa3d51f08e50a40e22e7f5cafb",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:42787b4df5a3f810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547813908451710",
    "url": "https://ci.chromium.org/b/8924014926217103120",
    "utcnow_ts": "1547813909042124"
  }
}
[12:18:29.050] Invocation finished in 5h45m22.775201887s with status FAILED