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

• 9f733c9d96b89a0889aea72fb1003f78fa4017cb (6 years ago)

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

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

[21:36:13.554] New invocation is queued and will start shortly
[21:36:15.216] Starting the invocation (attempt 1)
[21:36:15.293] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:36:15.293] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:36:15.293] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9087485632764360000",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"9f733c9d96b89a0889aea72fb1003f78fa4017cb\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_64",
    "scheduler_invocation_id:9087485632764360000",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
    "gitiles_ref:refs/heads/master"
  ]
}
[21:36:16.846] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1549661775475617",
    "id": "8922055570078290640",
    "lease_expiration_ts": "1549812976495046",
    "lease_key": "1885812511",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"9f733c9d96b89a0889aea72fb1003f78fa4017cb\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1549661776692191",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5064",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9087485632764360000",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8922055570078290640/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:42e7dbb0ec5b5510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1549661776692299",
    "url": "https://ci.chromium.org/b/8922055570078290640",
    "utcnow_ts": "1549661776837851"
  }
}
[21:36:16.846] Task URL: https://ci.chromium.org/b/8922055570078290640
[21:36:16.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:2:0) after 1m0s
[21:37:16.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:2:0)
[21:37:16.889] Timer tick, asking Buildbucket for the build status
[21:37:16.966] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:16.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:3:0) after 1m0s
[21:38:16.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:3:0)
[21:38:16.982] Timer tick, asking Buildbucket for the build status
[21:38:17.175] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:17.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:4:0) after 1m0s
[21:39:17.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:4:0)
[21:39:17.191] Timer tick, asking Buildbucket for the build status
[21:39:17.302] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:17.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:5:0) after 1m0s
[21:40:17.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:5:0)
[21:40:17.360] Timer tick, asking Buildbucket for the build status
[21:40:17.421] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:17.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:6:0) after 1m0s
[21:41:17.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:6:0)
[21:41:17.436] Timer tick, asking Buildbucket for the build status
[21:41:17.514] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:17.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:7:0) after 1m0s
[21:42:17.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:7:0)
[21:42:17.530] Timer tick, asking Buildbucket for the build status
[21:42:17.584] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:17.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:8:0) after 1m0s
[21:43:17.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:8:0)
[21:43:17.621] Timer tick, asking Buildbucket for the build status
[21:43:17.671] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:17.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:9:0) after 1m0s
[21:44:17.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:9:0)
[21:44:17.714] Timer tick, asking Buildbucket for the build status
[21:44:17.775] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:17.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:10:0) after 1m0s
[21:45:17.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:10:0)
[21:45:17.801] Timer tick, asking Buildbucket for the build status
[21:45:17.847] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:17.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:11:0) after 1m0s
[21:46:18.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:11:0)
[21:46:18.227] Timer tick, asking Buildbucket for the build status
[21:46:18.286] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:18.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:12:0) after 1m0s
[21:47:18.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:12:0)
[21:47:18.309] Timer tick, asking Buildbucket for the build status
[21:47:18.673] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:18.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:13:0) after 1m0s
[21:48:18.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:13:0)
[21:48:18.689] Timer tick, asking Buildbucket for the build status
[21:48:18.802] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:18.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:14:0) after 1m0s
[21:49:18.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:14:0)
[21:49:18.823] Timer tick, asking Buildbucket for the build status
[21:49:18.898] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:18.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:15:0) after 1m0s
[21:50:18.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:15:0)
[21:50:18.917] Timer tick, asking Buildbucket for the build status
[21:50:19.051] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:19.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:16:0) after 1m0s
[21:51:19.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:16:0)
[21:51:19.101] Timer tick, asking Buildbucket for the build status
[21:51:19.182] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:19.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:17:0) after 1m0s
[21:52:19.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:17:0)
[21:52:19.243] Timer tick, asking Buildbucket for the build status
[21:52:19.306] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:19.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:18:0) after 1m0s
[21:53:19.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:18:0)
[21:53:19.341] Timer tick, asking Buildbucket for the build status
[21:53:19.402] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:19.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:19:0) after 1m0s
[21:54:19.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:19:0)
[21:54:19.424] Timer tick, asking Buildbucket for the build status
[21:54:19.786] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:19.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:20:0) after 1m0s
[21:55:19.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:20:0)
[21:55:19.806] Timer tick, asking Buildbucket for the build status
[21:55:19.940] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:19.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:21:0) after 1m0s
[21:56:19.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:21:0)
[21:56:19.986] Timer tick, asking Buildbucket for the build status
[21:56:20.123] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:20.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:22:0) after 1m0s
[21:57:20.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:22:0)
[21:57:20.157] Timer tick, asking Buildbucket for the build status
[21:57:20.276] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:20.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:23:0) after 1m0s
[21:58:20.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:23:0)
[21:58:20.294] Timer tick, asking Buildbucket for the build status
[21:58:20.360] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:20.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:24:0) after 1m0s
[21:59:20.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:24:0)
[21:59:20.382] Timer tick, asking Buildbucket for the build status
[21:59:20.497] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:20.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:25:0) after 1m0s
[22:00:20.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:25:0)
[22:00:20.529] Timer tick, asking Buildbucket for the build status
[22:00:20.600] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:20.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:26:0) after 1m0s
[22:01:20.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:26:0)
[22:01:20.628] Timer tick, asking Buildbucket for the build status
[22:01:20.688] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:20.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:27:0) after 1m0s
[22:02:20.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:27:0)
[22:02:20.705] Timer tick, asking Buildbucket for the build status
[22:02:20.818] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:20.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:28:0) after 1m0s
[22:03:20.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:28:0)
[22:03:20.835] Timer tick, asking Buildbucket for the build status
[22:03:20.985] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:20.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:29:0) after 1m0s
[22:04:21.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:29:0)
[22:04:21.001] Timer tick, asking Buildbucket for the build status
[22:04:21.178] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:21.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:30:0) after 1m0s
[22:05:21.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:30:0)
[22:05:21.204] Timer tick, asking Buildbucket for the build status
[22:05:21.362] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:21.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:31:0) after 1m0s
[22:06:21.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:31:0)
[22:06:21.379] Timer tick, asking Buildbucket for the build status
[22:06:21.544] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:21.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:32:0) after 1m0s
[22:07:21.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:32:0)
[22:07:21.563] Timer tick, asking Buildbucket for the build status
[22:07:21.624] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:21.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:33:0) after 1m0s
[22:08:21.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:33:0)
[22:08:21.645] Timer tick, asking Buildbucket for the build status
[22:08:21.760] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:21.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:34:0) after 1m0s
[22:09:21.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:34:0)
[22:09:21.779] Timer tick, asking Buildbucket for the build status
[22:09:21.963] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:21.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:35:0) after 1m0s
[22:10:21.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:35:0)
[22:10:21.990] Timer tick, asking Buildbucket for the build status
[22:10:22.045] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:22.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:36:0) after 1m0s
[22:11:22.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:36:0)
[22:11:22.077] Timer tick, asking Buildbucket for the build status
[22:11:22.139] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:22.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:37:0) after 1m0s
[22:12:22.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:37:0)
[22:12:22.157] Timer tick, asking Buildbucket for the build status
[22:12:22.236] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:22.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:38:0) after 1m0s
[22:13:22.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:38:0)
[22:13:22.256] Timer tick, asking Buildbucket for the build status
[22:13:22.389] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:22.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:39:0) after 1m0s
[22:14:22.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:39:0)
[22:14:22.431] Timer tick, asking Buildbucket for the build status
[22:14:22.539] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:22.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:40:0) after 1m0s
[22:15:22.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:40:0)
[22:15:22.560] Timer tick, asking Buildbucket for the build status
[22:15:22.687] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:22.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:41:0) after 1m0s
[22:16:22.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:41:0)
[22:16:22.720] Timer tick, asking Buildbucket for the build status
[22:16:22.815] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:22.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:42:0) after 1m0s
[22:17:22.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:42:0)
[22:17:22.832] Timer tick, asking Buildbucket for the build status
[22:17:22.997] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:22.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:43:0) after 1m0s
[22:18:23.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:43:0)
[22:18:23.017] Timer tick, asking Buildbucket for the build status
[22:18:23.098] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:23.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:44:0) after 1m0s
[22:19:23.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:44:0)
[22:19:23.118] Timer tick, asking Buildbucket for the build status
[22:19:23.197] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:23.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:45:0) after 1m0s
[22:20:23.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:45:0)
[22:20:23.246] Timer tick, asking Buildbucket for the build status
[22:20:23.299] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:23.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:46:0) after 1m0s
[22:21:23.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:46:0)
[22:21:23.321] Timer tick, asking Buildbucket for the build status
[22:21:23.450] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:23.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:47:0) after 1m0s
[22:22:23.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:47:0)
[22:22:23.476] Timer tick, asking Buildbucket for the build status
[22:22:23.530] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:23.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:48:0) after 1m0s
[22:23:23.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:48:0)
[22:23:23.570] Timer tick, asking Buildbucket for the build status
[22:23:23.736] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:23.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:49:0) after 1m0s
[22:24:23.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:49:0)
[22:24:23.754] Timer tick, asking Buildbucket for the build status
[22:24:23.861] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:23.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:50:0) after 1m0s
[22:25:23.883] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:50:0)
[22:25:23.883] Timer tick, asking Buildbucket for the build status
[22:25:24.069] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:24.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:51:0) after 1m0s
[22:26:24.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:51:0)
[22:26:24.091] Timer tick, asking Buildbucket for the build status
[22:26:24.149] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:24.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:52:0) after 1m0s
[22:27:24.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:52:0)
[22:27:24.184] Timer tick, asking Buildbucket for the build status
[22:27:24.291] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:24.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:53:0) after 1m0s
[22:28:24.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:53:0)
[22:28:24.330] Timer tick, asking Buildbucket for the build status
[22:28:24.573] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:24.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:54:0) after 1m0s
[22:29:24.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:54:0)
[22:29:24.607] Timer tick, asking Buildbucket for the build status
[22:29:24.671] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:24.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:55:0) after 1m0s
[22:30:24.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:55:0)
[22:30:24.694] Timer tick, asking Buildbucket for the build status
[22:30:24.746] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:24.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:56:0) after 1m0s
[22:31:24.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:56:0)
[22:31:24.764] Timer tick, asking Buildbucket for the build status
[22:31:24.832] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:24.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:57:0) after 1m0s
[22:32:24.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:57:0)
[22:32:24.851] Timer tick, asking Buildbucket for the build status
[22:32:24.990] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:24.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:58:0) after 1m0s
[22:33:25.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:58:0)
[22:33:25.065] Timer tick, asking Buildbucket for the build status
[22:33:25.132] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:25.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:59:0) after 1m0s
[22:34:25.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:59:0)
[22:34:25.178] Timer tick, asking Buildbucket for the build status
[22:34:25.499] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:25.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:60:0) after 1m0s
[22:35:25.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:60:0)
[22:35:25.518] Timer tick, asking Buildbucket for the build status
[22:35:25.659] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:25.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:61:0) after 1m0s
[22:36:25.679] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:61:0)
[22:36:25.679] Timer tick, asking Buildbucket for the build status
[22:36:25.752] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:25.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:62:0) after 1m0s
[22:37:25.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:62:0)
[22:37:25.820] Timer tick, asking Buildbucket for the build status
[22:37:25.873] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:25.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:63:0) after 1m0s
[22:38:25.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:63:0)
[22:38:25.891] Timer tick, asking Buildbucket for the build status
[22:38:26.189] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:26.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:64:0) after 1m0s
[22:39:26.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:64:0)
[22:39:26.215] Timer tick, asking Buildbucket for the build status
[22:39:26.265] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:26.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:65:0) after 1m0s
[22:40:26.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:65:0)
[22:40:26.284] Timer tick, asking Buildbucket for the build status
[22:40:26.347] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:26.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:66:0) after 1m0s
[22:41:26.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:66:0)
[22:41:26.367] Timer tick, asking Buildbucket for the build status
[22:41:26.468] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:26.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:67:0) after 1m0s
[22:42:26.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:67:0)
[22:42:26.488] Timer tick, asking Buildbucket for the build status
[22:42:26.557] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:26.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:68:0) after 1m0s
[22:43:26.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:68:0)
[22:43:26.576] Timer tick, asking Buildbucket for the build status
[22:43:26.631] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:26.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:69:0) after 1m0s
[22:44:26.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:69:0)
[22:44:26.658] Timer tick, asking Buildbucket for the build status
[22:44:26.709] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:26.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:70:0) after 1m0s
[22:45:26.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:70:0)
[22:45:26.729] Timer tick, asking Buildbucket for the build status
[22:45:26.887] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:26.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:71:0) after 1m0s
[22:46:26.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:71:0)
[22:46:26.907] Timer tick, asking Buildbucket for the build status
[22:46:26.962] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:26.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:72:0) after 1m0s
[22:47:26.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:72:0)
[22:47:26.979] Timer tick, asking Buildbucket for the build status
[22:47:27.041] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:27.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:73:0) after 1m0s
[22:48:27.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:73:0)
[22:48:27.059] Timer tick, asking Buildbucket for the build status
[22:48:27.199] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:27.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:74:0) after 1m0s
[22:49:27.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:74:0)
[22:49:27.217] Timer tick, asking Buildbucket for the build status
[22:49:27.352] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:27.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:75:0) after 1m0s
[22:50:27.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:75:0)
[22:50:27.376] Timer tick, asking Buildbucket for the build status
[22:50:27.521] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:27.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:76:0) after 1m0s
[22:51:27.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:76:0)
[22:51:27.543] Timer tick, asking Buildbucket for the build status
[22:51:27.675] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:27.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:77:0) after 1m0s
[22:52:27.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:77:0)
[22:52:27.729] Timer tick, asking Buildbucket for the build status
[22:52:27.970] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:27.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:78:0) after 1m0s
[22:53:28.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:78:0)
[22:53:28.002] Timer tick, asking Buildbucket for the build status
[22:53:28.128] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:28.128] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:79:0) after 1m0s
[22:54:28.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:79:0)
[22:54:28.146] Timer tick, asking Buildbucket for the build status
[22:54:28.295] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:28.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:80:0) after 1m0s
[22:55:28.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:80:0)
[22:55:28.323] Timer tick, asking Buildbucket for the build status
[22:55:28.377] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:28.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:81:0) after 1m0s
[22:56:28.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:81:0)
[22:56:28.411] Timer tick, asking Buildbucket for the build status
[22:56:28.562] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:28.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:82:0) after 1m0s
[22:57:28.586] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:82:0)
[22:57:28.586] Timer tick, asking Buildbucket for the build status
[22:57:28.641] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:28.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:83:0) after 1m0s
[22:58:28.660] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:83:0)
[22:58:28.660] Timer tick, asking Buildbucket for the build status
[22:58:28.716] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:28.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:84:0) after 1m0s
[22:59:28.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:84:0)
[22:59:28.735] Timer tick, asking Buildbucket for the build status
[22:59:28.800] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:28.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:85:0) after 1m0s
[23:00:28.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:85:0)
[23:00:28.837] Timer tick, asking Buildbucket for the build status
[23:00:28.956] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:28.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:86:0) after 1m0s
[23:01:29.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:86:0)
[23:01:29.027] Timer tick, asking Buildbucket for the build status
[23:01:29.163] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:29.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:87:0) after 1m0s
[23:02:29.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:87:0)
[23:02:29.182] Timer tick, asking Buildbucket for the build status
[23:02:29.271] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:29.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:88:0) after 1m0s
[23:03:29.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:88:0)
[23:03:29.301] Timer tick, asking Buildbucket for the build status
[23:03:29.524] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:29.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:89:0) after 1m0s
[23:04:29.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:89:0)
[23:04:29.615] Timer tick, asking Buildbucket for the build status
[23:04:29.672] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:29.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:90:0) after 1m0s
[23:05:29.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:90:0)
[23:05:29.691] Timer tick, asking Buildbucket for the build status
[23:05:29.740] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:29.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:91:0) after 1m0s
[23:06:29.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:91:0)
[23:06:29.761] Timer tick, asking Buildbucket for the build status
[23:06:29.880] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:29.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:92:0) after 1m0s
[23:07:29.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:92:0)
[23:07:29.904] Timer tick, asking Buildbucket for the build status
[23:07:30.059] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:30.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:93:0) after 1m0s
[23:08:30.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:93:0)
[23:08:30.082] Timer tick, asking Buildbucket for the build status
[23:08:30.141] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:30.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:94:0) after 1m0s
[23:09:30.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:94:0)
[23:09:30.227] Timer tick, asking Buildbucket for the build status
[23:09:30.395] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:30.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:95:0) after 1m0s
[23:10:30.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:95:0)
[23:10:30.435] Timer tick, asking Buildbucket for the build status
[23:10:30.485] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:30.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:96:0) after 1m0s
[23:11:30.512] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:96:0)
[23:11:30.512] Timer tick, asking Buildbucket for the build status
[23:11:30.585] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:30.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:97:0) after 1m0s
[23:12:30.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:97:0)
[23:12:30.605] Timer tick, asking Buildbucket for the build status
[23:12:30.694] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:30.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:98:0) after 1m0s
[23:13:30.724] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:98:0)
[23:13:30.724] Timer tick, asking Buildbucket for the build status
[23:13:30.884] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:30.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:99:0) after 1m0s
[23:14:30.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:99:0)
[23:14:30.903] Timer tick, asking Buildbucket for the build status
[23:14:31.142] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:31.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:100:0) after 1m0s
[23:15:31.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:100:0)
[23:15:31.167] Timer tick, asking Buildbucket for the build status
[23:15:31.289] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:31.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:101:0) after 1m0s
[23:16:31.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:101:0)
[23:16:31.309] Timer tick, asking Buildbucket for the build status
[23:16:31.361] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:31.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:102:0) after 1m0s
[23:17:31.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:102:0)
[23:17:31.381] Timer tick, asking Buildbucket for the build status
[23:17:31.433] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:31.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:103:0) after 1m0s
[23:18:31.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:103:0)
[23:18:31.457] Timer tick, asking Buildbucket for the build status
[23:18:31.523] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:31.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:104:0) after 1m0s
[23:19:31.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:104:0)
[23:19:31.544] Timer tick, asking Buildbucket for the build status
[23:19:31.609] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:31.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:105:0) after 1m0s
[23:20:31.632] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:105:0)
[23:20:31.632] Timer tick, asking Buildbucket for the build status
[23:20:31.714] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:31.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:106:0) after 1m0s
[23:21:31.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:106:0)
[23:21:31.737] Timer tick, asking Buildbucket for the build status
[23:21:31.922] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:31.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:107:0) after 1m0s
[23:22:31.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:107:0)
[23:22:31.944] Timer tick, asking Buildbucket for the build status
[23:22:32.004] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:32.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:108:0) after 1m0s
[23:23:32.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:108:0)
[23:23:32.027] Timer tick, asking Buildbucket for the build status
[23:23:32.099] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:32.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:109:0) after 1m0s
[23:24:32.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:109:0)
[23:24:32.138] Timer tick, asking Buildbucket for the build status
[23:24:32.184] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:32.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:110:0) after 1m0s
[23:25:32.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:110:0)
[23:25:32.213] Timer tick, asking Buildbucket for the build status
[23:25:32.264] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:32.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:111:0) after 1m0s
[23:26:32.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:111:0)
[23:26:32.298] Timer tick, asking Buildbucket for the build status
[23:26:32.445] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:32.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:112:0) after 1m0s
[23:27:32.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:112:0)
[23:27:32.474] Timer tick, asking Buildbucket for the build status
[23:27:32.564] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:32.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:113:0) after 1m0s
[23:28:32.586] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:113:0)
[23:28:32.586] Timer tick, asking Buildbucket for the build status
[23:28:32.661] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:32.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:114:0) after 1m0s
[23:29:32.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:114:0)
[23:29:32.682] Timer tick, asking Buildbucket for the build status
[23:29:32.796] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:32.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:115:0) after 1m0s
[23:30:32.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:115:0)
[23:30:32.860] Timer tick, asking Buildbucket for the build status
[23:30:33.046] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:33.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:116:0) after 1m0s
[23:31:33.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:116:0)
[23:31:33.064] Timer tick, asking Buildbucket for the build status
[23:31:33.347] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:33.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:117:0) after 1m0s
[23:32:33.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:117:0)
[23:32:33.371] Timer tick, asking Buildbucket for the build status
[23:32:33.443] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:33.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:118:0) after 1m0s
[23:33:33.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:118:0)
[23:33:33.479] Timer tick, asking Buildbucket for the build status
[23:33:33.606] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:33.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:119:0) after 1m0s
[23:34:33.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:119:0)
[23:34:33.640] Timer tick, asking Buildbucket for the build status
[23:34:33.775] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:33.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:120:0) after 1m0s
[23:35:33.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:120:0)
[23:35:33.804] Timer tick, asking Buildbucket for the build status
[23:35:33.866] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:33.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:121:0) after 1m0s
[23:36:33.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:121:0)
[23:36:33.886] Timer tick, asking Buildbucket for the build status
[23:36:33.957] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:33.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:122:0) after 1m0s
[23:37:33.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:122:0)
[23:37:33.975] Timer tick, asking Buildbucket for the build status
[23:37:34.045] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:34.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:123:0) after 1m0s
[23:38:34.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:123:0)
[23:38:34.125] Timer tick, asking Buildbucket for the build status
[23:38:34.268] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:34.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:124:0) after 1m0s
[23:39:34.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:124:0)
[23:39:34.373] Timer tick, asking Buildbucket for the build status
[23:39:34.494] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:34.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:125:0) after 1m0s
[23:40:34.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:125:0)
[23:40:34.565] Timer tick, asking Buildbucket for the build status
[23:40:34.627] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:34.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:126:0) after 1m0s
[23:41:34.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:126:0)
[23:41:34.674] Timer tick, asking Buildbucket for the build status
[23:41:34.777] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:34.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:127:0) after 1m0s
[23:42:34.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:127:0)
[23:42:34.802] Timer tick, asking Buildbucket for the build status
[23:42:34.872] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:34.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:128:0) after 1m0s
[23:43:34.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:128:0)
[23:43:34.902] Timer tick, asking Buildbucket for the build status
[23:43:34.972] Build 8922055570078290640: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:34.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:129:0) after 1m0s
[23:43:47.564] Received PubSub notification, asking Buildbucket for the build status
[23:43:47.611] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:35.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:129:0)
[23:44:35.018] Timer tick, asking Buildbucket for the build status
[23:44:35.119] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:35.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:131:0) after 1m0s
[23:45:35.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:131:0)
[23:45:35.146] Timer tick, asking Buildbucket for the build status
[23:45:35.199] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:35.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:132:0) after 1m0s
[23:46:35.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:132:0)
[23:46:35.221] Timer tick, asking Buildbucket for the build status
[23:46:35.591] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:35.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:133:0) after 1m0s
[23:47:35.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:133:0)
[23:47:35.613] Timer tick, asking Buildbucket for the build status
[23:47:35.734] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:35.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:134:0) after 1m0s
[23:48:35.755] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:134:0)
[23:48:35.755] Timer tick, asking Buildbucket for the build status
[23:48:35.852] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:35.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:135:0) after 1m0s
[23:49:35.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:135:0)
[23:49:35.876] Timer tick, asking Buildbucket for the build status
[23:49:36.252] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:36.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:136:0) after 1m0s
[23:50:36.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:136:0)
[23:50:36.273] Timer tick, asking Buildbucket for the build status
[23:50:36.571] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:36.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:137:0) after 1m0s
[23:51:36.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:137:0)
[23:51:36.600] Timer tick, asking Buildbucket for the build status
[23:51:36.647] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:36.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:138:0) after 1m0s
[23:52:36.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:138:0)
[23:52:36.668] Timer tick, asking Buildbucket for the build status
[23:52:36.713] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:36.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:139:0) after 1m0s
[23:53:36.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:139:0)
[23:53:36.731] Timer tick, asking Buildbucket for the build status
[23:53:36.790] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:36.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:140:0) after 1m0s
[23:54:36.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:140:0)
[23:54:36.819] Timer tick, asking Buildbucket for the build status
[23:54:36.890] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:36.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:141:0) after 1m0s
[23:55:36.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:141:0)
[23:55:36.916] Timer tick, asking Buildbucket for the build status
[23:55:36.992] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:36.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:142:0) after 1m0s
[23:56:37.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:142:0)
[23:56:37.012] Timer tick, asking Buildbucket for the build status
[23:56:37.121] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:37.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:143:0) after 1m0s
[23:57:37.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:143:0)
[23:57:37.155] Timer tick, asking Buildbucket for the build status
[23:57:37.309] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:37.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:144:0) after 1m0s
[23:58:37.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:144:0)
[23:58:37.331] Timer tick, asking Buildbucket for the build status
[23:58:37.428] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:37.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:145:0) after 1m0s
[23:59:37.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:145:0)
[23:59:37.449] Timer tick, asking Buildbucket for the build status
[23:59:37.537] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:37.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:146:0) after 1m0s
[00:00:37.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:146:0)
[00:00:37.564] Timer tick, asking Buildbucket for the build status
[00:00:37.732] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:37.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:147:0) after 1m0s
[00:01:37.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:147:0)
[00:01:37.774] Timer tick, asking Buildbucket for the build status
[00:01:37.865] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:37.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:148:0) after 1m0s
[00:02:37.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:148:0)
[00:02:37.889] Timer tick, asking Buildbucket for the build status
[00:02:38.068] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:38.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:149:0) after 1m0s
[00:03:38.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:149:0)
[00:03:38.090] Timer tick, asking Buildbucket for the build status
[00:03:38.222] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:38.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:150:0) after 1m0s
[00:04:38.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:150:0)
[00:04:38.243] Timer tick, asking Buildbucket for the build status
[00:04:38.494] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:38.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:151:0) after 1m0s
[00:05:38.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:151:0)
[00:05:38.514] Timer tick, asking Buildbucket for the build status
[00:05:38.624] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:38.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:152:0) after 1m0s
[00:06:38.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:152:0)
[00:06:38.668] Timer tick, asking Buildbucket for the build status
[00:06:38.878] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:38.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:153:0) after 1m0s
[00:07:38.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:153:0)
[00:07:38.900] Timer tick, asking Buildbucket for the build status
[00:07:39.630] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:39.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:154:0) after 1m0s
[00:08:39.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:154:0)
[00:08:39.652] Timer tick, asking Buildbucket for the build status
[00:08:39.862] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:39.862] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:155:0) after 1m0s
[00:09:39.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:155:0)
[00:09:39.885] Timer tick, asking Buildbucket for the build status
[00:09:40.005] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:40.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:156:0) after 1m0s
[00:10:40.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:156:0)
[00:10:40.029] Timer tick, asking Buildbucket for the build status
[00:10:40.886] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:40.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:157:0) after 1m0s
[00:11:40.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:157:0)
[00:11:40.907] Timer tick, asking Buildbucket for the build status
[00:11:41.008] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:41.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:158:0) after 1m0s
[00:12:41.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:158:0)
[00:12:41.031] Timer tick, asking Buildbucket for the build status
[00:12:41.080] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:41.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:159:0) after 1m0s
[00:13:41.100] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:159:0)
[00:13:41.100] Timer tick, asking Buildbucket for the build status
[00:13:41.540] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:41.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:160:0) after 1m0s
[00:14:41.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:160:0)
[00:14:41.561] Timer tick, asking Buildbucket for the build status
[00:14:41.683] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:41.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:161:0) after 1m0s
[00:15:41.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:161:0)
[00:15:41.705] Timer tick, asking Buildbucket for the build status
[00:15:41.757] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:41.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:162:0) after 1m0s
[00:16:41.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:162:0)
[00:16:41.802] Timer tick, asking Buildbucket for the build status
[00:16:41.968] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:41.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:163:0) after 1m0s
[00:17:42.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:163:0)
[00:17:42.029] Timer tick, asking Buildbucket for the build status
[00:17:42.140] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:42.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:164:0) after 1m0s
[00:18:42.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:164:0)
[00:18:42.166] Timer tick, asking Buildbucket for the build status
[00:18:42.291] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:42.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:165:0) after 1m0s
[00:19:42.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:165:0)
[00:19:42.321] Timer tick, asking Buildbucket for the build status
[00:19:42.413] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:42.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:166:0) after 1m0s
[00:20:42.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:166:0)
[00:20:42.442] Timer tick, asking Buildbucket for the build status
[00:20:42.508] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:42.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:167:0) after 1m0s
[00:21:42.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:167:0)
[00:21:42.538] Timer tick, asking Buildbucket for the build status
[00:21:42.641] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:42.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:168:0) after 1m0s
[00:22:42.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:168:0)
[00:22:42.671] Timer tick, asking Buildbucket for the build status
[00:22:42.831] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:42.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:169:0) after 1m0s
[00:23:42.919] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:169:0)
[00:23:42.919] Timer tick, asking Buildbucket for the build status
[00:23:43.232] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:43.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:170:0) after 1m0s
[00:24:43.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:170:0)
[00:24:43.309] Timer tick, asking Buildbucket for the build status
[00:24:43.360] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:43.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:171:0) after 1m0s
[00:25:43.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:171:0)
[00:25:43.381] Timer tick, asking Buildbucket for the build status
[00:25:43.463] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:43.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:172:0) after 1m0s
[00:26:43.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:172:0)
[00:26:43.491] Timer tick, asking Buildbucket for the build status
[00:26:43.568] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:43.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:173:0) after 1m0s
[00:27:43.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:173:0)
[00:27:43.645] Timer tick, asking Buildbucket for the build status
[00:27:43.695] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:43.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:174:0) after 1m0s
[00:28:43.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:174:0)
[00:28:43.740] Timer tick, asking Buildbucket for the build status
[00:28:44.037] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:44.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:175:0) after 1m0s
[00:29:44.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:175:0)
[00:29:44.068] Timer tick, asking Buildbucket for the build status
[00:29:44.117] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:44.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:176:0) after 1m0s
[00:30:44.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:176:0)
[00:30:44.141] Timer tick, asking Buildbucket for the build status
[00:30:44.188] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:44.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:177:0) after 1m0s
[00:31:44.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:177:0)
[00:31:44.214] Timer tick, asking Buildbucket for the build status
[00:31:44.381] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:44.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:178:0) after 1m0s
[00:32:44.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:178:0)
[00:32:44.406] Timer tick, asking Buildbucket for the build status
[00:32:44.505] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:44.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:179:0) after 1m0s
[00:33:44.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:179:0)
[00:33:44.651] Timer tick, asking Buildbucket for the build status
[00:33:44.727] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:44.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:180:0) after 1m0s
[00:34:44.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:180:0)
[00:34:44.774] Timer tick, asking Buildbucket for the build status
[00:34:44.829] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:44.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:181:0) after 1m0s
[00:35:44.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:181:0)
[00:35:44.864] Timer tick, asking Buildbucket for the build status
[00:35:44.991] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:44.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:182:0) after 1m0s
[00:36:45.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:182:0)
[00:36:45.096] Timer tick, asking Buildbucket for the build status
[00:36:45.247] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:45.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:183:0) after 1m0s
[00:37:45.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:183:0)
[00:37:45.397] Timer tick, asking Buildbucket for the build status
[00:37:45.530] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:45.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:184:0) after 1m0s
[00:38:45.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:184:0)
[00:38:45.566] Timer tick, asking Buildbucket for the build status
[00:38:45.626] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:45.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:185:0) after 1m0s
[00:39:45.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:185:0)
[00:39:45.652] Timer tick, asking Buildbucket for the build status
[00:39:45.725] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:45.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:186:0) after 1m0s
[00:40:45.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:186:0)
[00:40:45.751] Timer tick, asking Buildbucket for the build status
[00:40:45.830] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:45.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:187:0) after 1m0s
[00:41:45.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:187:0)
[00:41:45.926] Timer tick, asking Buildbucket for the build status
[00:41:45.983] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:45.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:188:0) after 1m0s
[00:42:46.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:188:0)
[00:42:46.011] Timer tick, asking Buildbucket for the build status
[00:42:46.147] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:46.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:189:0) after 1m0s
[00:43:46.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:189:0)
[00:43:46.184] Timer tick, asking Buildbucket for the build status
[00:43:46.477] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:46.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:190:0) after 1m0s
[00:44:46.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:190:0)
[00:44:46.504] Timer tick, asking Buildbucket for the build status
[00:44:46.549] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:46.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:191:0) after 1m0s
[00:45:46.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:191:0)
[00:45:46.578] Timer tick, asking Buildbucket for the build status
[00:45:46.680] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:46.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:192:0) after 1m0s
[00:46:46.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:192:0)
[00:46:46.705] Timer tick, asking Buildbucket for the build status
[00:46:46.756] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:46.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:193:0) after 1m0s
[00:47:46.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:193:0)
[00:47:46.781] Timer tick, asking Buildbucket for the build status
[00:47:46.824] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:46.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:194:0) after 1m0s
[00:48:46.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:194:0)
[00:48:46.850] Timer tick, asking Buildbucket for the build status
[00:48:46.911] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:46.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:195:0) after 1m0s
[00:49:46.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:195:0)
[00:49:46.934] Timer tick, asking Buildbucket for the build status
[00:49:46.987] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:46.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:196:0) after 1m0s
[00:50:47.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:196:0)
[00:50:47.009] Timer tick, asking Buildbucket for the build status
[00:50:47.057] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:47.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:197:0) after 1m0s
[00:51:47.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:197:0)
[00:51:47.084] Timer tick, asking Buildbucket for the build status
[00:51:47.145] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:47.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:198:0) after 1m0s
[00:52:47.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:198:0)
[00:52:47.171] Timer tick, asking Buildbucket for the build status
[00:52:47.213] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:47.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:199:0) after 1m0s
[00:53:47.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:199:0)
[00:53:47.238] Timer tick, asking Buildbucket for the build status
[00:53:47.289] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:47.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:200:0) after 1m0s
[00:54:47.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:200:0)
[00:54:47.328] Timer tick, asking Buildbucket for the build status
[00:54:47.378] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:47.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:201:0) after 1m0s
[00:55:47.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:201:0)
[00:55:47.419] Timer tick, asking Buildbucket for the build status
[00:55:47.795] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:47.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:202:0) after 1m0s
[00:56:47.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:202:0)
[00:56:47.824] Timer tick, asking Buildbucket for the build status
[00:56:47.909] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:47.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:203:0) after 1m0s
[00:57:47.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:203:0)
[00:57:47.932] Timer tick, asking Buildbucket for the build status
[00:57:47.996] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:47.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:204:0) after 1m0s
[00:58:48.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:204:0)
[00:58:48.033] Timer tick, asking Buildbucket for the build status
[00:58:48.103] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:48.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:205:0) after 1m0s
[00:59:48.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:205:0)
[00:59:48.131] Timer tick, asking Buildbucket for the build status
[00:59:48.209] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:48.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:206:0) after 1m0s
[01:00:48.283] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:206:0)
[01:00:48.283] Timer tick, asking Buildbucket for the build status
[01:00:48.330] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:48.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:207:0) after 1m0s
[01:01:48.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:207:0)
[01:01:48.355] Timer tick, asking Buildbucket for the build status
[01:01:48.402] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:48.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:208:0) after 1m0s
[01:02:48.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:208:0)
[01:02:48.449] Timer tick, asking Buildbucket for the build status
[01:02:48.577] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:48.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:209:0) after 1m0s
[01:03:48.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:209:0)
[01:03:48.603] Timer tick, asking Buildbucket for the build status
[01:03:48.645] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:48.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:210:0) after 1m0s
[01:04:48.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:210:0)
[01:04:48.669] Timer tick, asking Buildbucket for the build status
[01:04:48.719] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:48.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:211:0) after 1m0s
[01:05:48.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:211:0)
[01:05:48.747] Timer tick, asking Buildbucket for the build status
[01:05:48.901] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:48.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:212:0) after 1m0s
[01:06:48.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:212:0)
[01:06:48.929] Timer tick, asking Buildbucket for the build status
[01:06:49.020] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:49.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:213:0) after 1m0s
[01:07:49.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:213:0)
[01:07:49.042] Timer tick, asking Buildbucket for the build status
[01:07:49.133] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:49.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:214:0) after 1m0s
[01:08:49.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:214:0)
[01:08:49.161] Timer tick, asking Buildbucket for the build status
[01:08:49.264] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:49.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:215:0) after 1m0s
[01:09:49.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:215:0)
[01:09:49.289] Timer tick, asking Buildbucket for the build status
[01:09:49.411] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:49.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:216:0) after 1m0s
[01:10:49.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:216:0)
[01:10:49.456] Timer tick, asking Buildbucket for the build status
[01:10:49.724] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:49.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:217:0) after 1m0s
[01:11:49.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:217:0)
[01:11:49.835] Timer tick, asking Buildbucket for the build status
[01:11:49.944] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:49.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:218:0) after 1m0s
[01:12:49.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:218:0)
[01:12:49.969] Timer tick, asking Buildbucket for the build status
[01:12:50.087] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:50.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:219:0) after 1m0s
[01:13:50.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:219:0)
[01:13:50.116] Timer tick, asking Buildbucket for the build status
[01:13:50.205] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:50.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:220:0) after 1m0s
[01:14:50.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:220:0)
[01:14:50.262] Timer tick, asking Buildbucket for the build status
[01:14:50.400] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:50.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:221:0) after 1m0s
[01:15:50.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:221:0)
[01:15:50.425] Timer tick, asking Buildbucket for the build status
[01:15:50.479] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:50.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:222:0) after 1m0s
[01:16:50.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:222:0)
[01:16:50.526] Timer tick, asking Buildbucket for the build status
[01:16:50.764] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:50.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:223:0) after 1m0s
[01:17:50.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:223:0)
[01:17:50.793] Timer tick, asking Buildbucket for the build status
[01:17:50.931] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:50.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:224:0) after 1m0s
[01:18:50.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:224:0)
[01:18:50.954] Timer tick, asking Buildbucket for the build status
[01:18:51.018] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:51.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:225:0) after 1m0s
[01:19:51.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:225:0)
[01:19:51.039] Timer tick, asking Buildbucket for the build status
[01:19:51.124] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:51.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:226:0) after 1m0s
[01:20:51.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:226:0)
[01:20:51.150] Timer tick, asking Buildbucket for the build status
[01:20:51.231] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:51.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:227:0) after 1m0s
[01:21:51.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:227:0)
[01:21:51.254] Timer tick, asking Buildbucket for the build status
[01:21:51.301] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:51.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:228:0) after 1m0s
[01:22:51.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:228:0)
[01:22:51.322] Timer tick, asking Buildbucket for the build status
[01:22:51.647] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:51.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:229:0) after 1m0s
[01:23:51.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:229:0)
[01:23:51.672] Timer tick, asking Buildbucket for the build status
[01:23:51.757] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:51.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:230:0) after 1m0s
[01:24:51.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:230:0)
[01:24:51.786] Timer tick, asking Buildbucket for the build status
[01:24:51.912] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:51.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:231:0) after 1m0s
[01:25:51.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:231:0)
[01:25:51.935] Timer tick, asking Buildbucket for the build status
[01:25:52.132] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:52.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:232:0) after 1m0s
[01:26:52.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:232:0)
[01:26:52.155] Timer tick, asking Buildbucket for the build status
[01:26:52.245] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:52.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:233:0) after 1m0s
[01:27:52.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:233:0)
[01:27:52.267] Timer tick, asking Buildbucket for the build status
[01:27:52.570] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:52.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:234:0) after 1m0s
[01:28:52.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:234:0)
[01:28:52.592] Timer tick, asking Buildbucket for the build status
[01:28:52.647] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:52.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:235:0) after 1m0s
[01:29:52.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:235:0)
[01:29:52.675] Timer tick, asking Buildbucket for the build status
[01:29:52.898] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:52.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:236:0) after 1m0s
[01:30:52.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:236:0)
[01:30:52.924] Timer tick, asking Buildbucket for the build status
[01:30:53.104] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:53.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:237:0) after 1m0s
[01:31:53.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:237:0)
[01:31:53.133] Timer tick, asking Buildbucket for the build status
[01:31:53.205] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:53.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:238:0) after 1m0s
[01:32:53.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:238:0)
[01:32:53.254] Timer tick, asking Buildbucket for the build status
[01:32:53.313] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:53.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:239:0) after 1m0s
[01:33:53.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:239:0)
[01:33:53.342] Timer tick, asking Buildbucket for the build status
[01:33:53.632] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:53.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:240:0) after 1m0s
[01:34:53.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:240:0)
[01:34:53.659] Timer tick, asking Buildbucket for the build status
[01:34:53.940] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:53.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:241:0) after 1m0s
[01:35:53.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:241:0)
[01:35:53.965] Timer tick, asking Buildbucket for the build status
[01:35:54.069] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:54.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:242:0) after 1m0s
[01:36:54.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:242:0)
[01:36:54.105] Timer tick, asking Buildbucket for the build status
[01:36:54.211] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:54.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:243:0) after 1m0s
[01:37:54.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:243:0)
[01:37:54.271] Timer tick, asking Buildbucket for the build status
[01:37:54.367] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:54.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:244:0) after 1m0s
[01:38:54.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:244:0)
[01:38:54.397] Timer tick, asking Buildbucket for the build status
[01:38:54.495] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:54.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:245:0) after 1m0s
[01:39:54.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:245:0)
[01:39:54.553] Timer tick, asking Buildbucket for the build status
[01:39:54.646] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:54.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:246:0) after 1m0s
[01:40:54.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:246:0)
[01:40:54.689] Timer tick, asking Buildbucket for the build status
[01:40:54.968] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:54.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:247:0) after 1m0s
[01:41:54.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:247:0)
[01:41:54.993] Timer tick, asking Buildbucket for the build status
[01:41:55.097] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:55.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:248:0) after 1m0s
[01:42:55.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:248:0)
[01:42:55.127] Timer tick, asking Buildbucket for the build status
[01:42:55.267] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:55.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:249:0) after 1m0s
[01:43:55.300] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:249:0)
[01:43:55.300] Timer tick, asking Buildbucket for the build status
[01:43:55.367] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:55.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:250:0) after 1m0s
[01:44:55.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:250:0)
[01:44:55.394] Timer tick, asking Buildbucket for the build status
[01:44:55.454] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:55.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:251:0) after 1m0s
[01:45:55.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:251:0)
[01:45:55.483] Timer tick, asking Buildbucket for the build status
[01:45:55.571] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:55.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:252:0) after 1m0s
[01:46:55.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:252:0)
[01:46:55.599] Timer tick, asking Buildbucket for the build status
[01:46:55.740] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:55.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:253:0) after 1m0s
[01:47:55.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:253:0)
[01:47:55.862] Timer tick, asking Buildbucket for the build status
[01:47:55.948] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:55.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:254:0) after 1m0s
[01:48:56.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:254:0)
[01:48:56.076] Timer tick, asking Buildbucket for the build status
[01:48:56.149] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:255:0) after 1m0s
[01:49:56.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:255:0)
[01:49:56.279] Timer tick, asking Buildbucket for the build status
[01:49:56.381] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:56.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:256:0) after 1m0s
[01:50:56.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:256:0)
[01:50:56.408] Timer tick, asking Buildbucket for the build status
[01:50:56.459] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:56.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:257:0) after 1m0s
[01:51:56.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:257:0)
[01:51:56.595] Timer tick, asking Buildbucket for the build status
[01:51:56.671] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:56.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:258:0) after 1m0s
[01:52:56.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:258:0)
[01:52:56.757] Timer tick, asking Buildbucket for the build status
[01:52:57.078] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:57.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:259:0) after 1m0s
[01:53:57.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:259:0)
[01:53:57.101] Timer tick, asking Buildbucket for the build status
[01:53:57.206] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:57.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:260:0) after 1m0s
[01:54:57.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:260:0)
[01:54:57.236] Timer tick, asking Buildbucket for the build status
[01:54:57.412] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:57.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:261:0) after 1m0s
[01:55:57.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:261:0)
[01:55:57.442] Timer tick, asking Buildbucket for the build status
[01:55:57.504] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:57.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:262:0) after 1m0s
[01:56:57.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:262:0)
[01:56:57.532] Timer tick, asking Buildbucket for the build status
[01:56:57.619] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:57.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:263:0) after 1m0s
[01:57:57.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:263:0)
[01:57:57.651] Timer tick, asking Buildbucket for the build status
[01:57:58.274] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:58.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:264:0) after 1m0s
[01:58:58.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:264:0)
[01:58:58.320] Timer tick, asking Buildbucket for the build status
[01:58:58.690] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:58.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:265:0) after 1m0s
[01:59:58.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:265:0)
[01:59:58.729] Timer tick, asking Buildbucket for the build status
[01:59:58.819] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:58.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:266:0) after 1m0s
[02:00:58.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:266:0)
[02:00:58.849] Timer tick, asking Buildbucket for the build status
[02:00:58.998] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:58.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:267:0) after 1m0s
[02:01:59.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:267:0)
[02:01:59.029] Timer tick, asking Buildbucket for the build status
[02:01:59.097] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:268:0) after 1m0s
[02:02:59.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:268:0)
[02:02:59.132] Timer tick, asking Buildbucket for the build status
[02:02:59.272] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:269:0) after 1m0s
[02:03:59.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:269:0)
[02:03:59.299] Timer tick, asking Buildbucket for the build status
[02:03:59.481] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:59.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:270:0) after 1m0s
[02:04:59.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:270:0)
[02:04:59.515] Timer tick, asking Buildbucket for the build status
[02:04:59.597] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:59.597] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:271:0) after 1m0s
[02:05:59.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:271:0)
[02:05:59.671] Timer tick, asking Buildbucket for the build status
[02:05:59.771] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:59.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:272:0) after 1m0s
[02:06:59.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:272:0)
[02:06:59.818] Timer tick, asking Buildbucket for the build status
[02:07:00.083] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:00.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:273:0) after 1m0s
[02:08:00.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:273:0)
[02:08:00.116] Timer tick, asking Buildbucket for the build status
[02:08:00.310] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:00.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:274:0) after 1m0s
[02:09:00.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:274:0)
[02:09:00.442] Timer tick, asking Buildbucket for the build status
[02:09:00.666] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:00.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:275:0) after 1m0s
[02:10:00.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:275:0)
[02:10:00.734] Timer tick, asking Buildbucket for the build status
[02:10:00.825] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:00.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:276:0) after 1m0s
[02:11:00.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:276:0)
[02:11:00.868] Timer tick, asking Buildbucket for the build status
[02:11:00.942] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:00.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:277:0) after 1m0s
[02:12:00.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:277:0)
[02:12:00.966] Timer tick, asking Buildbucket for the build status
[02:12:01.179] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:01.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:278:0) after 1m0s
[02:13:01.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:278:0)
[02:13:01.208] Timer tick, asking Buildbucket for the build status
[02:13:01.278] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:01.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:279:0) after 1m0s
[02:14:01.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:279:0)
[02:14:01.309] Timer tick, asking Buildbucket for the build status
[02:14:01.677] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:01.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:280:0) after 1m0s
[02:15:01.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:280:0)
[02:15:01.703] Timer tick, asking Buildbucket for the build status
[02:15:01.762] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:01.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:281:0) after 1m0s
[02:16:01.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:281:0)
[02:16:01.791] Timer tick, asking Buildbucket for the build status
[02:16:01.942] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:01.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:282:0) after 1m0s
[02:17:01.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:282:0)
[02:17:01.983] Timer tick, asking Buildbucket for the build status
[02:17:02.073] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:02.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:283:0) after 1m0s
[02:18:02.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:283:0)
[02:18:02.130] Timer tick, asking Buildbucket for the build status
[02:18:02.252] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:02.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:284:0) after 1m0s
[02:19:02.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:284:0)
[02:19:02.308] Timer tick, asking Buildbucket for the build status
[02:19:02.441] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:02.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:285:0) after 1m0s
[02:20:02.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:285:0)
[02:20:02.465] Timer tick, asking Buildbucket for the build status
[02:20:02.988] Build 8922055570078290640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:02.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9087485632764360000:286:0) after 1m0s
[02:20:26.839] Received PubSub notification, asking Buildbucket for the build status
[02:20:26.949] Build 8922055570078290640: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:20:26.949] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1549678825346317",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1549661775475617",
    "failure_reason": "BUILD_FAILURE",
    "id": "8922055570078290640",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"9f733c9d96b89a0889aea72fb1003f78fa4017cb\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"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\": [\"4077-0a4fdfc\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-141h\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b_sz\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 5064, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"linux-pnacl-x86_64-tests-x86_64\", \"key\": \"builder\"}, {\"value\": \"commit/git/9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"key\": \"buildset\"}, {\"value\": \"9087485632764360000\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"nacl/linux-pnacl-x86_64-tests-x86_64\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"nacl\", \"builder\": \"linux-pnacl-x86_64-tests-x86_64\", \"bucket\": \"toolchain\"}, \"number\": 5064, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ef156230f5cd7f8c23f689fa4dc7f92742cd904d\"}, \"recipe\": {\"name\": \"nacl\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922055570078290640\", \"input\": {\"gitilesCommit\": {\"project\": \"native_client/src/native_client\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"9f733c9d96b89a0889aea72fb1003f78fa4017cb\"}}, \"createTime\": \"2019-02-08T21:36:15.475617Z\"}}, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"got_revision\": \"9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549661775475617, \"tags\": [\"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5064\", \"builder:linux-pnacl-x86_64-tests-x86_64\", \"buildset:commit/git/9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087485632764360000\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8922055570078290640\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"9f733c9d96b89a0889aea72fb1003f78fa4017cb\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-141h\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1549669426852124",
    "status": "COMPLETED",
    "status_changed_ts": "1549678826360143",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5064",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f733c9d96b89a0889aea72fb1003f78fa4017cb",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9087485632764360000",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8922055570078290640/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:42e7dbb0ec5b5510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1549678826361823",
    "url": "https://ci.chromium.org/b/8922055570078290640",
    "utcnow_ts": "1549678826935947"
  }
}
[02:20:26.949] Invocation finished in 4h44m13.420750984s with status FAILED