LUCI Scheduler :: nacl :: linux-pnacl-x86_64

Status: FAILED

Triggered by: -

Duration: 6 hours

Actions:

Definition (rev 1c82d86159c2071b02a7ddf08ffd2ae0bff92186)

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

Incoming triggers

db355f48151995834fbdddd464fc00b8ad265132 (6 years ago)

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[00:32:47.513] New invocation is queued and will start shortly
[00:32:49.000] Starting the invocation (attempt 1)
[00:32:49.038] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:32:49.038] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:32:49.038] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9089467657445892240",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"db355f48151995834fbdddd464fc00b8ad265132\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64",
    "scheduler_invocation_id:9089467657445892240",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/db355f48151995834fbdddd464fc00b8ad265132",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db355f48151995834fbdddd464fc00b8ad265132",
    "gitiles_ref:refs/heads/master"
  ]
}
[00:32:50.809] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547771569121258",
    "id": "8924037595096141024",
    "lease_expiration_ts": "1547922770213792",
    "lease_key": "1139749231",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"db355f48151995834fbdddd464fc00b8ad265132\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1547771570511821",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7019",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/db355f48151995834fbdddd464fc00b8ad265132",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db355f48151995834fbdddd464fc00b8ad265132",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089467657445892240",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_dimension:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7019",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8924037595096141024",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64",
      "swarming_tag:buildset:commit/git/db355f48151995834fbdddd464fc00b8ad265132",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db355f48151995834fbdddd464fc00b8ad265132",
      "swarming_tag:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924037595096141024/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089467657445892240",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:7bd44c5698750454da45e03e17f93c16ef58f23d",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4277316e99765010",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547771570511924",
    "url": "https://ci.chromium.org/b/8924037595096141024",
    "utcnow_ts": "1547771570764363"
  }
}
[00:32:50.809] Task URL: https://ci.chromium.org/b/8924037595096141024
[00:32:50.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:2:0) after 1m0s
[00:33:50.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:2:0)
[00:33:50.977] Timer tick, asking Buildbucket for the build status
[00:33:51.042] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:51.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:3:0) after 1m0s
[00:34:51.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:3:0)
[00:34:51.381] Timer tick, asking Buildbucket for the build status
[00:34:51.419] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:51.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:4:0) after 1m0s
[00:35:51.512] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:4:0)
[00:35:51.512] Timer tick, asking Buildbucket for the build status
[00:35:51.564] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:51.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:5:0) after 1m0s
[00:36:51.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:5:0)
[00:36:51.596] Timer tick, asking Buildbucket for the build status
[00:36:51.643] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:51.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:6:0) after 1m0s
[00:37:51.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:6:0)
[00:37:51.665] Timer tick, asking Buildbucket for the build status
[00:37:51.750] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:51.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:7:0) after 1m0s
[00:38:51.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:7:0)
[00:38:51.770] Timer tick, asking Buildbucket for the build status
[00:38:51.852] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:51.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:8:0) after 1m0s
[00:39:51.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:8:0)
[00:39:51.871] Timer tick, asking Buildbucket for the build status
[00:39:52.051] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:52.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:9:0) after 1m0s
[00:40:52.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:9:0)
[00:40:52.072] Timer tick, asking Buildbucket for the build status
[00:40:52.113] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:52.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:10:0) after 1m0s
[00:41:52.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:10:0)
[00:41:52.134] Timer tick, asking Buildbucket for the build status
[00:41:52.179] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:52.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:11:0) after 1m0s
[00:42:52.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:11:0)
[00:42:52.196] Timer tick, asking Buildbucket for the build status
[00:42:52.451] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:52.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:12:0) after 1m0s
[00:43:52.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:12:0)
[00:43:52.471] Timer tick, asking Buildbucket for the build status
[00:43:52.522] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:52.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:13:0) after 1m0s
[00:44:52.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:13:0)
[00:44:52.538] Timer tick, asking Buildbucket for the build status
[00:44:52.599] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:52.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:14:0) after 1m0s
[00:45:52.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:14:0)
[00:45:52.620] Timer tick, asking Buildbucket for the build status
[00:45:52.666] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:52.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:15:0) after 1m0s
[00:46:52.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:15:0)
[00:46:52.686] Timer tick, asking Buildbucket for the build status
[00:46:52.744] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:52.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:16:0) after 1m0s
[00:47:52.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:16:0)
[00:47:52.792] Timer tick, asking Buildbucket for the build status
[00:47:52.879] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:52.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:17:0) after 1m0s
[00:48:52.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:17:0)
[00:48:52.908] Timer tick, asking Buildbucket for the build status
[00:48:52.997] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:52.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:18:0) after 1m0s
[00:49:53.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:18:0)
[00:49:53.017] Timer tick, asking Buildbucket for the build status
[00:49:53.071] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:53.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:19:0) after 1m0s
[00:50:53.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:19:0)
[00:50:53.189] Timer tick, asking Buildbucket for the build status
[00:50:53.231] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:53.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:20:0) after 1m0s
[00:51:53.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:20:0)
[00:51:53.249] Timer tick, asking Buildbucket for the build status
[00:51:53.305] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:53.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:21:0) after 1m0s
[00:52:53.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:21:0)
[00:52:53.324] Timer tick, asking Buildbucket for the build status
[00:52:53.392] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:53.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:22:0) after 1m0s
[00:53:53.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:22:0)
[00:53:53.417] Timer tick, asking Buildbucket for the build status
[00:53:53.450] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:53.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:23:0) after 1m0s
[00:54:53.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:23:0)
[00:54:53.562] Timer tick, asking Buildbucket for the build status
[00:54:53.613] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:53.613] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:24:0) after 1m0s
[00:55:53.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:24:0)
[00:55:53.634] Timer tick, asking Buildbucket for the build status
[00:55:53.666] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:53.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:25:0) after 1m0s
[00:56:53.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:25:0)
[00:56:53.687] Timer tick, asking Buildbucket for the build status
[00:56:53.744] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:53.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:26:0) after 1m0s
[00:57:53.760] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:26:0)
[00:57:53.760] Timer tick, asking Buildbucket for the build status
[00:57:53.799] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:53.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:27:0) after 1m0s
[00:58:53.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:27:0)
[00:58:53.818] Timer tick, asking Buildbucket for the build status
[00:58:53.924] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:53.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:28:0) after 1m0s
[00:59:53.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:28:0)
[00:59:53.949] Timer tick, asking Buildbucket for the build status
[00:59:54.109] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:54.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:29:0) after 1m0s
[01:00:54.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:29:0)
[01:00:54.127] Timer tick, asking Buildbucket for the build status
[01:00:54.246] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:54.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:30:0) after 1m0s
[01:01:54.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:30:0)
[01:01:54.317] Timer tick, asking Buildbucket for the build status
[01:01:54.386] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:54.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:31:0) after 1m0s
[01:02:54.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:31:0)
[01:02:54.409] Timer tick, asking Buildbucket for the build status
[01:02:54.443] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:54.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:32:0) after 1m0s
[01:03:54.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:32:0)
[01:03:54.462] Timer tick, asking Buildbucket for the build status
[01:03:54.567] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:54.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:33:0) after 1m0s
[01:04:54.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:33:0)
[01:04:54.635] Timer tick, asking Buildbucket for the build status
[01:04:54.770] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:54.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:34:0) after 1m0s
[01:05:54.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:34:0)
[01:05:54.787] Timer tick, asking Buildbucket for the build status
[01:05:54.853] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:54.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:35:0) after 1m0s
[01:06:54.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:35:0)
[01:06:54.874] Timer tick, asking Buildbucket for the build status
[01:06:54.936] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:54.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:36:0) after 1m0s
[01:07:54.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:36:0)
[01:07:54.956] Timer tick, asking Buildbucket for the build status
[01:07:54.996] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:54.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:37:0) after 1m0s
[01:08:55.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:37:0)
[01:08:55.029] Timer tick, asking Buildbucket for the build status
[01:08:55.488] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:55.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:38:0) after 1m0s
[01:09:55.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:38:0)
[01:09:55.524] Timer tick, asking Buildbucket for the build status
[01:09:55.597] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:55.597] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:39:0) after 1m0s
[01:10:55.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:39:0)
[01:10:55.643] Timer tick, asking Buildbucket for the build status
[01:10:55.697] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:55.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:40:0) after 1m0s
[01:11:55.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:40:0)
[01:11:55.720] Timer tick, asking Buildbucket for the build status
[01:11:55.768] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:55.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:41:0) after 1m0s
[01:12:55.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:41:0)
[01:12:55.851] Timer tick, asking Buildbucket for the build status
[01:12:55.975] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:55.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:42:0) after 1m0s
[01:13:55.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:42:0)
[01:13:55.995] Timer tick, asking Buildbucket for the build status
[01:13:56.068] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:56.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:43:0) after 1m0s
[01:14:56.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:43:0)
[01:14:56.108] Timer tick, asking Buildbucket for the build status
[01:14:56.162] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:56.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:44:0) after 1m0s
[01:15:56.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:44:0)
[01:15:56.180] Timer tick, asking Buildbucket for the build status
[01:15:56.269] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:56.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:45:0) after 1m0s
[01:16:56.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:45:0)
[01:16:56.297] Timer tick, asking Buildbucket for the build status
[01:16:56.400] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:56.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:46:0) after 1m0s
[01:17:56.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:46:0)
[01:17:56.418] Timer tick, asking Buildbucket for the build status
[01:17:56.475] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:56.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:47:0) after 1m0s
[01:18:56.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:47:0)
[01:18:56.494] Timer tick, asking Buildbucket for the build status
[01:18:56.683] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:56.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:48:0) after 1m0s
[01:19:56.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:48:0)
[01:19:56.701] Timer tick, asking Buildbucket for the build status
[01:19:56.751] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:56.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:49:0) after 1m0s
[01:20:56.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:49:0)
[01:20:56.793] Timer tick, asking Buildbucket for the build status
[01:20:56.856] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:56.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:50:0) after 1m0s
[01:21:56.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:50:0)
[01:21:56.876] Timer tick, asking Buildbucket for the build status
[01:21:56.964] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:56.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:51:0) after 1m0s
[01:22:57.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:51:0)
[01:22:57.003] Timer tick, asking Buildbucket for the build status
[01:22:57.042] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:57.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:52:0) after 1m0s
[01:23:57.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:52:0)
[01:23:57.062] Timer tick, asking Buildbucket for the build status
[01:23:57.112] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:57.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:53:0) after 1m0s
[01:24:57.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:53:0)
[01:24:57.138] Timer tick, asking Buildbucket for the build status
[01:24:57.220] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:57.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:54:0) after 1m0s
[01:25:57.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:54:0)
[01:25:57.244] Timer tick, asking Buildbucket for the build status
[01:25:57.369] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:57.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:55:0) after 1m0s
[01:26:57.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:55:0)
[01:26:57.389] Timer tick, asking Buildbucket for the build status
[01:26:57.432] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:57.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:56:0) after 1m0s
[01:27:57.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:56:0)
[01:27:57.515] Timer tick, asking Buildbucket for the build status
[01:27:57.616] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:57.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:57:0) after 1m0s
[01:28:57.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:57:0)
[01:28:57.635] Timer tick, asking Buildbucket for the build status
[01:28:57.807] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:57.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:58:0) after 1m0s
[01:29:57.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:58:0)
[01:29:57.831] Timer tick, asking Buildbucket for the build status
[01:29:57.874] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:57.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:59:0) after 1m0s
[01:30:57.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:59:0)
[01:30:57.903] Timer tick, asking Buildbucket for the build status
[01:30:57.965] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:57.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:60:0) after 1m0s
[01:31:57.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:60:0)
[01:31:57.984] Timer tick, asking Buildbucket for the build status
[01:31:58.020] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:58.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:61:0) after 1m0s
[01:32:58.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:61:0)
[01:32:58.121] Timer tick, asking Buildbucket for the build status
[01:32:58.287] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:58.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:62:0) after 1m0s
[01:33:58.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:62:0)
[01:33:58.306] Timer tick, asking Buildbucket for the build status
[01:33:58.337] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:58.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:63:0) after 1m0s
[01:34:58.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:63:0)
[01:34:58.363] Timer tick, asking Buildbucket for the build status
[01:34:58.421] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:58.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:64:0) after 1m0s
[01:35:58.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:64:0)
[01:35:58.439] Timer tick, asking Buildbucket for the build status
[01:35:58.477] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:58.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:65:0) after 1m0s
[01:36:58.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:65:0)
[01:36:58.498] Timer tick, asking Buildbucket for the build status
[01:36:58.622] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:58.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:66:0) after 1m0s
[01:37:58.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:66:0)
[01:37:58.649] Timer tick, asking Buildbucket for the build status
[01:37:58.719] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:58.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:67:0) after 1m0s
[01:38:58.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:67:0)
[01:38:58.739] Timer tick, asking Buildbucket for the build status
[01:38:59.172] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:59.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:68:0) after 1m0s
[01:39:59.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:68:0)
[01:39:59.229] Timer tick, asking Buildbucket for the build status
[01:39:59.298] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:59.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:69:0) after 1m0s
[01:40:59.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:69:0)
[01:40:59.329] Timer tick, asking Buildbucket for the build status
[01:40:59.417] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:59.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:70:0) after 1m0s
[01:41:59.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:70:0)
[01:41:59.438] Timer tick, asking Buildbucket for the build status
[01:41:59.566] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:59.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:71:0) after 1m0s
[01:42:59.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:71:0)
[01:42:59.590] Timer tick, asking Buildbucket for the build status
[01:42:59.674] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:59.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:72:0) after 1m0s
[01:43:59.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:72:0)
[01:43:59.697] Timer tick, asking Buildbucket for the build status
[01:43:59.727] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:59.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:73:0) after 1m0s
[01:44:59.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:73:0)
[01:44:59.748] Timer tick, asking Buildbucket for the build status
[01:44:59.938] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:59.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:74:0) after 1m0s
[01:45:59.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:74:0)
[01:45:59.958] Timer tick, asking Buildbucket for the build status
[01:45:59.990] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:59.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:75:0) after 1m0s
[01:47:00.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:75:0)
[01:47:00.007] Timer tick, asking Buildbucket for the build status
[01:47:00.205] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:00.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:76:0) after 1m0s
[01:48:00.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:76:0)
[01:48:00.238] Timer tick, asking Buildbucket for the build status
[01:48:00.268] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:00.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:77:0) after 1m0s
[01:49:00.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:77:0)
[01:49:00.285] Timer tick, asking Buildbucket for the build status
[01:49:00.379] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:00.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:78:0) after 1m0s
[01:50:00.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:78:0)
[01:50:00.400] Timer tick, asking Buildbucket for the build status
[01:50:00.455] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:00.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:79:0) after 1m0s
[01:51:00.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:79:0)
[01:51:00.493] Timer tick, asking Buildbucket for the build status
[01:51:00.544] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:00.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:80:0) after 1m0s
[01:52:00.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:80:0)
[01:52:00.563] Timer tick, asking Buildbucket for the build status
[01:52:00.660] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:00.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:81:0) after 1m0s
[01:53:00.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:81:0)
[01:53:00.683] Timer tick, asking Buildbucket for the build status
[01:53:00.760] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:00.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:82:0) after 1m0s
[01:54:00.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:82:0)
[01:54:00.782] Timer tick, asking Buildbucket for the build status
[01:54:00.818] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:00.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:83:0) after 1m0s
[01:55:00.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:83:0)
[01:55:00.878] Timer tick, asking Buildbucket for the build status
[01:55:01.022] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:01.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:84:0) after 1m0s
[01:56:01.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:84:0)
[01:56:01.067] Timer tick, asking Buildbucket for the build status
[01:56:01.104] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:01.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:85:0) after 1m0s
[01:57:01.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:85:0)
[01:57:01.127] Timer tick, asking Buildbucket for the build status
[01:57:01.265] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:01.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:86:0) after 1m0s
[01:58:01.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:86:0)
[01:58:01.304] Timer tick, asking Buildbucket for the build status
[01:58:01.421] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:01.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:87:0) after 1m0s
[01:59:01.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:87:0)
[01:59:01.442] Timer tick, asking Buildbucket for the build status
[01:59:01.650] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:01.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:88:0) after 1m0s
[02:00:01.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:88:0)
[02:00:01.669] Timer tick, asking Buildbucket for the build status
[02:00:01.734] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:01.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:89:0) after 1m0s
[02:01:01.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:89:0)
[02:01:01.756] Timer tick, asking Buildbucket for the build status
[02:01:01.921] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:01.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:90:0) after 1m0s
[02:02:01.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:90:0)
[02:02:01.952] Timer tick, asking Buildbucket for the build status
[02:02:01.992] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:01.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:91:0) after 1m0s
[02:03:02.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:91:0)
[02:03:02.021] Timer tick, asking Buildbucket for the build status
[02:03:02.092] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:02.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:92:0) after 1m0s
[02:04:02.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:92:0)
[02:04:02.166] Timer tick, asking Buildbucket for the build status
[02:04:02.202] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:02.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:93:0) after 1m0s
[02:05:02.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:93:0)
[02:05:02.234] Timer tick, asking Buildbucket for the build status
[02:05:02.355] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:02.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:94:0) after 1m0s
[02:06:02.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:94:0)
[02:06:02.495] Timer tick, asking Buildbucket for the build status
[02:06:02.534] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:02.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:95:0) after 1m0s
[02:07:02.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:95:0)
[02:07:02.566] Timer tick, asking Buildbucket for the build status
[02:07:02.620] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:02.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:96:0) after 1m0s
[02:08:02.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:96:0)
[02:08:02.688] Timer tick, asking Buildbucket for the build status
[02:08:02.742] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:02.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:97:0) after 1m0s
[02:09:02.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:97:0)
[02:09:02.767] Timer tick, asking Buildbucket for the build status
[02:09:02.839] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:02.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:98:0) after 1m0s
[02:10:02.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:98:0)
[02:10:02.865] Timer tick, asking Buildbucket for the build status
[02:10:02.967] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:02.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:99:0) after 1m0s
[02:11:02.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:99:0)
[02:11:02.988] Timer tick, asking Buildbucket for the build status
[02:11:03.073] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:03.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:100:0) after 1m0s
[02:12:03.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:100:0)
[02:12:03.118] Timer tick, asking Buildbucket for the build status
[02:12:03.164] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:03.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:101:0) after 1m0s
[02:13:03.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:101:0)
[02:13:03.226] Timer tick, asking Buildbucket for the build status
[02:13:03.266] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:03.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:102:0) after 1m0s
[02:14:03.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:102:0)
[02:14:03.289] Timer tick, asking Buildbucket for the build status
[02:14:03.346] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:03.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:103:0) after 1m0s
[02:15:03.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:103:0)
[02:15:03.366] Timer tick, asking Buildbucket for the build status
[02:15:03.496] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:03.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:104:0) after 1m0s
[02:16:03.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:104:0)
[02:16:03.519] Timer tick, asking Buildbucket for the build status
[02:16:03.664] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:03.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:105:0) after 1m0s
[02:17:03.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:105:0)
[02:17:03.701] Timer tick, asking Buildbucket for the build status
[02:17:03.930] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:03.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:106:0) after 1m0s
[02:18:03.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:106:0)
[02:18:03.987] Timer tick, asking Buildbucket for the build status
[02:18:04.033] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:04.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:107:0) after 1m0s
[02:19:04.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:107:0)
[02:19:04.053] Timer tick, asking Buildbucket for the build status
[02:19:04.113] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:04.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:108:0) after 1m0s
[02:20:04.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:108:0)
[02:20:04.221] Timer tick, asking Buildbucket for the build status
[02:20:04.333] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:04.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:109:0) after 1m0s
[02:21:04.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:109:0)
[02:21:04.354] Timer tick, asking Buildbucket for the build status
[02:21:04.518] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:04.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:110:0) after 1m0s
[02:22:04.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:110:0)
[02:22:04.541] Timer tick, asking Buildbucket for the build status
[02:22:04.711] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:04.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:111:0) after 1m0s
[02:23:04.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:111:0)
[02:23:04.742] Timer tick, asking Buildbucket for the build status
[02:23:04.798] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:04.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:112:0) after 1m0s
[02:24:04.822] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:112:0)
[02:24:04.822] Timer tick, asking Buildbucket for the build status
[02:24:04.860] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:04.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:113:0) after 1m0s
[02:25:04.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:113:0)
[02:25:04.898] Timer tick, asking Buildbucket for the build status
[02:25:04.999] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:04.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:114:0) after 1m0s
[02:26:05.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:114:0)
[02:26:05.057] Timer tick, asking Buildbucket for the build status
[02:26:05.105] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:05.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:115:0) after 1m0s
[02:27:05.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:115:0)
[02:27:05.129] Timer tick, asking Buildbucket for the build status
[02:27:05.187] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:05.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:116:0) after 1m0s
[02:28:05.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:116:0)
[02:28:05.207] Timer tick, asking Buildbucket for the build status
[02:28:05.241] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:05.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:117:0) after 1m0s
[02:29:05.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:117:0)
[02:29:05.267] Timer tick, asking Buildbucket for the build status
[02:29:05.352] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:05.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:118:0) after 1m0s
[02:30:05.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:118:0)
[02:30:05.411] Timer tick, asking Buildbucket for the build status
[02:30:05.740] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:05.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:119:0) after 1m0s
[02:31:05.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:119:0)
[02:31:05.762] Timer tick, asking Buildbucket for the build status
[02:31:05.800] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:05.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:120:0) after 1m0s
[02:32:05.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:120:0)
[02:32:05.947] Timer tick, asking Buildbucket for the build status
[02:32:05.990] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:05.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:121:0) after 1m0s
[02:33:06.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:121:0)
[02:33:06.021] Timer tick, asking Buildbucket for the build status
[02:33:06.067] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:06.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:122:0) after 1m0s
[02:34:06.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:122:0)
[02:34:06.140] Timer tick, asking Buildbucket for the build status
[02:34:06.203] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:06.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:123:0) after 1m0s
[02:35:06.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:123:0)
[02:35:06.224] Timer tick, asking Buildbucket for the build status
[02:35:06.332] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:06.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:124:0) after 1m0s
[02:36:06.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:124:0)
[02:36:06.374] Timer tick, asking Buildbucket for the build status
[02:36:06.470] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:06.470] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:125:0) after 1m0s
[02:37:06.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:125:0)
[02:37:06.504] Timer tick, asking Buildbucket for the build status
[02:37:06.630] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:06.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:126:0) after 1m0s
[02:38:06.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:126:0)
[02:38:06.649] Timer tick, asking Buildbucket for the build status
[02:38:06.704] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:06.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:127:0) after 1m0s
[02:39:06.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:127:0)
[02:39:06.729] Timer tick, asking Buildbucket for the build status
[02:39:06.941] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:06.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:128:0) after 1m0s
[02:40:07.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:128:0)
[02:40:07.029] Timer tick, asking Buildbucket for the build status
[02:40:07.149] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:07.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:129:0) after 1m0s
[02:41:07.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:129:0)
[02:41:07.172] Timer tick, asking Buildbucket for the build status
[02:41:07.231] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:07.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:130:0) after 1m0s
[02:42:07.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:130:0)
[02:42:07.264] Timer tick, asking Buildbucket for the build status
[02:42:07.577] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:07.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:131:0) after 1m0s
[02:43:07.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:131:0)
[02:43:07.598] Timer tick, asking Buildbucket for the build status
[02:43:07.637] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:07.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:132:0) after 1m0s
[02:44:07.660] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:132:0)
[02:44:07.660] Timer tick, asking Buildbucket for the build status
[02:44:07.721] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:07.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:133:0) after 1m0s
[02:45:07.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:133:0)
[02:45:07.742] Timer tick, asking Buildbucket for the build status
[02:45:07.782] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:07.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:134:0) after 1m0s
[02:46:07.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:134:0)
[02:46:07.801] Timer tick, asking Buildbucket for the build status
[02:46:07.859] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:07.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:135:0) after 1m0s
[02:47:07.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:135:0)
[02:47:07.941] Timer tick, asking Buildbucket for the build status
[02:47:08.003] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:08.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:136:0) after 1m0s
[02:48:08.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:136:0)
[02:48:08.025] Timer tick, asking Buildbucket for the build status
[02:48:08.125] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:08.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:137:0) after 1m0s
[02:49:08.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:137:0)
[02:49:08.224] Timer tick, asking Buildbucket for the build status
[02:49:08.267] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:08.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:138:0) after 1m0s
[02:50:08.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:138:0)
[02:50:08.318] Timer tick, asking Buildbucket for the build status
[02:50:08.416] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:08.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:139:0) after 1m0s
[02:51:08.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:139:0)
[02:51:08.440] Timer tick, asking Buildbucket for the build status
[02:51:08.497] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:08.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:140:0) after 1m0s
[02:52:08.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:140:0)
[02:52:08.564] Timer tick, asking Buildbucket for the build status
[02:52:08.654] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:08.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:141:0) after 1m0s
[02:53:08.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:141:0)
[02:53:08.678] Timer tick, asking Buildbucket for the build status
[02:53:08.834] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:08.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:142:0) after 1m0s
[02:54:08.855] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:142:0)
[02:54:08.855] Timer tick, asking Buildbucket for the build status
[02:54:08.887] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:08.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:143:0) after 1m0s
[02:55:08.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:143:0)
[02:55:08.907] Timer tick, asking Buildbucket for the build status
[02:55:08.969] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:08.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:144:0) after 1m0s
[02:56:08.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:144:0)
[02:56:08.990] Timer tick, asking Buildbucket for the build status
[02:56:09.048] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:09.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:145:0) after 1m0s
[02:57:09.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:145:0)
[02:57:09.076] Timer tick, asking Buildbucket for the build status
[02:57:09.153] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:09.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:146:0) after 1m0s
[02:58:09.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:146:0)
[02:58:09.186] Timer tick, asking Buildbucket for the build status
[02:58:09.301] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:09.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:147:0) after 1m0s
[02:59:09.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:147:0)
[02:59:09.342] Timer tick, asking Buildbucket for the build status
[02:59:09.408] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:09.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:148:0) after 1m0s
[03:00:09.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:148:0)
[03:00:09.455] Timer tick, asking Buildbucket for the build status
[03:00:09.532] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:09.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:149:0) after 1m0s
[03:01:09.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:149:0)
[03:01:09.553] Timer tick, asking Buildbucket for the build status
[03:01:09.803] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:09.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:150:0) after 1m0s
[03:02:09.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:150:0)
[03:02:09.839] Timer tick, asking Buildbucket for the build status
[03:02:09.916] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:09.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:151:0) after 1m0s
[03:03:09.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:151:0)
[03:03:09.935] Timer tick, asking Buildbucket for the build status
[03:03:10.001] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:10.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:152:0) after 1m0s
[03:04:10.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:152:0)
[03:04:10.027] Timer tick, asking Buildbucket for the build status
[03:04:10.063] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:10.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:153:0) after 1m0s
[03:05:10.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:153:0)
[03:05:10.103] Timer tick, asking Buildbucket for the build status
[03:05:10.151] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:10.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:154:0) after 1m0s
[03:06:10.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:154:0)
[03:06:10.182] Timer tick, asking Buildbucket for the build status
[03:06:10.238] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:10.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:155:0) after 1m0s
[03:07:10.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:155:0)
[03:07:10.265] Timer tick, asking Buildbucket for the build status
[03:07:10.838] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:10.838] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:156:0) after 1m0s
[03:08:10.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:156:0)
[03:08:10.871] Timer tick, asking Buildbucket for the build status
[03:08:10.948] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:10.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:157:0) after 1m0s
[03:09:10.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:157:0)
[03:09:10.971] Timer tick, asking Buildbucket for the build status
[03:09:11.005] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:11.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:158:0) after 1m0s
[03:10:11.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:158:0)
[03:10:11.035] Timer tick, asking Buildbucket for the build status
[03:10:11.113] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:11.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:159:0) after 1m0s
[03:11:11.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:159:0)
[03:11:11.141] Timer tick, asking Buildbucket for the build status
[03:11:11.257] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:11.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:160:0) after 1m0s
[03:12:11.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:160:0)
[03:12:11.281] Timer tick, asking Buildbucket for the build status
[03:12:11.316] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:11.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:161:0) after 1m0s
[03:13:11.370] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:161:0)
[03:13:11.370] Timer tick, asking Buildbucket for the build status
[03:13:11.429] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:11.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:162:0) after 1m0s
[03:14:11.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:162:0)
[03:14:11.449] Timer tick, asking Buildbucket for the build status
[03:14:11.506] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:11.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:163:0) after 1m0s
[03:15:11.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:163:0)
[03:15:11.529] Timer tick, asking Buildbucket for the build status
[03:15:11.712] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:11.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:164:0) after 1m0s
[03:16:11.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:164:0)
[03:16:11.823] Timer tick, asking Buildbucket for the build status
[03:16:11.917] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:11.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:165:0) after 1m0s
[03:17:11.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:165:0)
[03:17:11.937] Timer tick, asking Buildbucket for the build status
[03:17:12.016] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:12.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:166:0) after 1m0s
[03:18:12.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:166:0)
[03:18:12.075] Timer tick, asking Buildbucket for the build status
[03:18:12.114] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:12.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:167:0) after 1m0s
[03:19:12.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:167:0)
[03:19:12.187] Timer tick, asking Buildbucket for the build status
[03:19:12.432] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:12.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:168:0) after 1m0s
[03:20:12.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:168:0)
[03:20:12.461] Timer tick, asking Buildbucket for the build status
[03:20:12.503] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:12.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:169:0) after 1m0s
[03:21:12.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:169:0)
[03:21:12.527] Timer tick, asking Buildbucket for the build status
[03:21:12.562] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:12.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:170:0) after 1m0s
[03:22:12.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:170:0)
[03:22:12.668] Timer tick, asking Buildbucket for the build status
[03:22:12.814] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:12.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:171:0) after 1m0s
[03:23:12.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:171:0)
[03:23:12.912] Timer tick, asking Buildbucket for the build status
[03:23:13.011] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:13.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:172:0) after 1m0s
[03:24:13.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:172:0)
[03:24:13.099] Timer tick, asking Buildbucket for the build status
[03:24:13.154] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:13.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:173:0) after 1m0s
[03:25:13.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:173:0)
[03:25:13.178] Timer tick, asking Buildbucket for the build status
[03:25:13.209] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:13.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:174:0) after 1m0s
[03:26:13.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:174:0)
[03:26:13.275] Timer tick, asking Buildbucket for the build status
[03:26:13.335] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:13.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:175:0) after 1m0s
[03:27:13.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:175:0)
[03:27:13.382] Timer tick, asking Buildbucket for the build status
[03:27:13.464] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:13.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:176:0) after 1m0s
[03:28:13.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:176:0)
[03:28:13.517] Timer tick, asking Buildbucket for the build status
[03:28:13.559] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:13.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:177:0) after 1m0s
[03:29:13.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:177:0)
[03:29:13.582] Timer tick, asking Buildbucket for the build status
[03:29:13.665] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:13.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:178:0) after 1m0s
[03:30:13.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:178:0)
[03:30:13.739] Timer tick, asking Buildbucket for the build status
[03:30:13.805] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:13.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:179:0) after 1m0s
[03:31:13.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:179:0)
[03:31:13.882] Timer tick, asking Buildbucket for the build status
[03:31:13.938] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:13.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:180:0) after 1m0s
[03:32:13.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:180:0)
[03:32:13.928] Timer tick, asking Buildbucket for the build status
[03:32:13.965] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:13.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:181:0) after 1m0s
[03:33:14.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:181:0)
[03:33:14.007] Timer tick, asking Buildbucket for the build status
[03:33:14.039] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:14.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:182:0) after 1m0s
[03:34:14.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:182:0)
[03:34:14.169] Timer tick, asking Buildbucket for the build status
[03:34:14.247] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:14.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:183:0) after 1m0s
[03:35:14.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:183:0)
[03:35:14.314] Timer tick, asking Buildbucket for the build status
[03:35:14.352] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:14.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:184:0) after 1m0s
[03:36:14.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:184:0)
[03:36:14.381] Timer tick, asking Buildbucket for the build status
[03:36:14.457] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:14.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:185:0) after 1m0s
[03:37:14.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:185:0)
[03:37:14.481] Timer tick, asking Buildbucket for the build status
[03:37:14.553] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:14.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:186:0) after 1m0s
[03:38:14.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:186:0)
[03:38:14.585] Timer tick, asking Buildbucket for the build status
[03:38:14.637] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:14.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:187:0) after 1m0s
[03:39:14.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:187:0)
[03:39:14.708] Timer tick, asking Buildbucket for the build status
[03:39:14.738] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:14.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:188:0) after 1m0s
[03:40:14.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:188:0)
[03:40:14.761] Timer tick, asking Buildbucket for the build status
[03:40:14.831] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:14.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:189:0) after 1m0s
[03:41:14.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:189:0)
[03:41:14.852] Timer tick, asking Buildbucket for the build status
[03:41:14.908] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:14.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:190:0) after 1m0s
[03:42:14.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:190:0)
[03:42:14.944] Timer tick, asking Buildbucket for the build status
[03:42:14.987] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:14.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:191:0) after 1m0s
[03:43:15.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:191:0)
[03:43:15.059] Timer tick, asking Buildbucket for the build status
[03:43:15.199] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:15.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:192:0) after 1m0s
[03:44:15.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:192:0)
[03:44:15.222] Timer tick, asking Buildbucket for the build status
[03:44:15.275] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:15.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:193:0) after 1m0s
[03:45:15.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:193:0)
[03:45:15.308] Timer tick, asking Buildbucket for the build status
[03:45:15.364] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:15.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:194:0) after 1m0s
[03:46:15.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:194:0)
[03:46:15.394] Timer tick, asking Buildbucket for the build status
[03:46:15.442] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:15.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:195:0) after 1m0s
[03:47:15.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:195:0)
[03:47:15.551] Timer tick, asking Buildbucket for the build status
[03:47:15.649] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:15.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:196:0) after 1m0s
[03:48:15.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:196:0)
[03:48:15.684] Timer tick, asking Buildbucket for the build status
[03:48:15.752] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:15.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:197:0) after 1m0s
[03:49:15.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:197:0)
[03:49:15.780] Timer tick, asking Buildbucket for the build status
[03:49:15.935] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:15.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:198:0) after 1m0s
[03:50:15.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:198:0)
[03:50:15.983] Timer tick, asking Buildbucket for the build status
[03:50:16.048] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:16.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:199:0) after 1m0s
[03:51:16.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:199:0)
[03:51:16.074] Timer tick, asking Buildbucket for the build status
[03:51:16.135] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:16.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:200:0) after 1m0s
[03:52:16.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:200:0)
[03:52:16.194] Timer tick, asking Buildbucket for the build status
[03:52:16.244] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:16.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:201:0) after 1m0s
[03:53:16.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:201:0)
[03:53:16.346] Timer tick, asking Buildbucket for the build status
[03:53:16.531] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:16.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:202:0) after 1m0s
[03:54:16.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:202:0)
[03:54:16.559] Timer tick, asking Buildbucket for the build status
[03:54:16.679] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:16.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:203:0) after 1m0s
[03:55:16.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:203:0)
[03:55:16.705] Timer tick, asking Buildbucket for the build status
[03:55:16.854] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:16.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:204:0) after 1m0s
[03:56:16.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:204:0)
[03:56:16.885] Timer tick, asking Buildbucket for the build status
[03:56:16.959] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:16.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:205:0) after 1m0s
[03:57:16.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:205:0)
[03:57:16.982] Timer tick, asking Buildbucket for the build status
[03:57:17.054] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:17.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:206:0) after 1m0s
[03:58:17.135] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:206:0)
[03:58:17.135] Timer tick, asking Buildbucket for the build status
[03:58:17.186] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:17.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:207:0) after 1m0s
[03:59:17.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:207:0)
[03:59:17.214] Timer tick, asking Buildbucket for the build status
[03:59:17.295] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:17.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:208:0) after 1m0s
[04:00:17.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:208:0)
[04:00:17.323] Timer tick, asking Buildbucket for the build status
[04:00:17.499] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:17.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:209:0) after 1m0s
[04:01:17.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:209:0)
[04:01:17.525] Timer tick, asking Buildbucket for the build status
[04:01:17.680] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:17.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:210:0) after 1m0s
[04:02:17.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:210:0)
[04:02:17.712] Timer tick, asking Buildbucket for the build status
[04:02:17.785] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:17.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:211:0) after 1m0s
[04:03:17.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:211:0)
[04:03:17.848] Timer tick, asking Buildbucket for the build status
[04:03:18.050] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:18.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:212:0) after 1m0s
[04:04:18.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:212:0)
[04:04:18.102] Timer tick, asking Buildbucket for the build status
[04:04:18.156] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:18.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:213:0) after 1m0s
[04:05:18.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:213:0)
[04:05:18.183] Timer tick, asking Buildbucket for the build status
[04:05:18.356] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:18.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:214:0) after 1m0s
[04:06:18.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:214:0)
[04:06:18.393] Timer tick, asking Buildbucket for the build status
[04:06:18.582] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:18.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:215:0) after 1m0s
[04:07:18.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:215:0)
[04:07:18.627] Timer tick, asking Buildbucket for the build status
[04:07:18.669] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:18.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:216:0) after 1m0s
[04:08:18.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:216:0)
[04:08:18.700] Timer tick, asking Buildbucket for the build status
[04:08:18.906] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:18.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:217:0) after 1m0s
[04:09:18.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:217:0)
[04:09:18.939] Timer tick, asking Buildbucket for the build status
[04:09:18.981] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:18.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:218:0) after 1m0s
[04:10:19.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:218:0)
[04:10:19.007] Timer tick, asking Buildbucket for the build status
[04:10:19.073] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:19.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:219:0) after 1m0s
[04:11:19.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:219:0)
[04:11:19.176] Timer tick, asking Buildbucket for the build status
[04:11:19.415] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:19.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:220:0) after 1m0s
[04:12:19.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:220:0)
[04:12:19.447] Timer tick, asking Buildbucket for the build status
[04:12:19.535] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:19.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:221:0) after 1m0s
[04:13:19.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:221:0)
[04:13:19.566] Timer tick, asking Buildbucket for the build status
[04:13:19.709] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:19.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:222:0) after 1m0s
[04:14:19.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:222:0)
[04:14:19.733] Timer tick, asking Buildbucket for the build status
[04:14:20.054] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:20.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:223:0) after 1m0s
[04:15:20.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:223:0)
[04:15:20.091] Timer tick, asking Buildbucket for the build status
[04:15:20.145] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:20.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:224:0) after 1m0s
[04:16:20.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:224:0)
[04:16:20.193] Timer tick, asking Buildbucket for the build status
[04:16:20.233] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:20.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:225:0) after 1m0s
[04:17:20.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:225:0)
[04:17:20.274] Timer tick, asking Buildbucket for the build status
[04:17:20.348] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:20.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:226:0) after 1m0s
[04:18:20.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:226:0)
[04:18:20.382] Timer tick, asking Buildbucket for the build status
[04:18:20.439] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:20.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:227:0) after 1m0s
[04:19:20.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:227:0)
[04:19:20.468] Timer tick, asking Buildbucket for the build status
[04:19:20.716] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:20.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:228:0) after 1m0s
[04:20:20.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:228:0)
[04:20:20.739] Timer tick, asking Buildbucket for the build status
[04:20:20.951] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:20.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:229:0) after 1m0s
[04:21:20.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:229:0)
[04:21:20.978] Timer tick, asking Buildbucket for the build status
[04:21:21.125] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:21.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:230:0) after 1m0s
[04:22:21.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:230:0)
[04:22:21.155] Timer tick, asking Buildbucket for the build status
[04:22:21.306] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:21.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:231:0) after 1m0s
[04:23:21.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:231:0)
[04:23:21.333] Timer tick, asking Buildbucket for the build status
[04:23:21.381] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:21.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:232:0) after 1m0s
[04:24:21.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:232:0)
[04:24:21.405] Timer tick, asking Buildbucket for the build status
[04:24:21.482] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:21.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:233:0) after 1m0s
[04:25:21.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:233:0)
[04:25:21.506] Timer tick, asking Buildbucket for the build status
[04:25:21.717] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:21.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:234:0) after 1m0s
[04:26:21.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:234:0)
[04:26:21.742] Timer tick, asking Buildbucket for the build status
[04:26:21.943] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:21.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:235:0) after 1m0s
[04:27:22.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:235:0)
[04:27:22.019] Timer tick, asking Buildbucket for the build status
[04:27:22.083] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:22.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:236:0) after 1m0s
[04:28:22.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:236:0)
[04:28:22.158] Timer tick, asking Buildbucket for the build status
[04:28:22.202] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:22.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:237:0) after 1m0s
[04:29:22.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:237:0)
[04:29:22.227] Timer tick, asking Buildbucket for the build status
[04:29:22.315] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:22.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:238:0) after 1m0s
[04:30:22.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:238:0)
[04:30:22.342] Timer tick, asking Buildbucket for the build status
[04:30:22.425] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:22.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:239:0) after 1m0s
[04:31:22.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:239:0)
[04:31:22.483] Timer tick, asking Buildbucket for the build status
[04:31:22.554] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:22.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:240:0) after 1m0s
[04:32:22.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:240:0)
[04:32:22.574] Timer tick, asking Buildbucket for the build status
[04:32:22.643] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:22.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:241:0) after 1m0s
[04:33:22.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:241:0)
[04:33:22.672] Timer tick, asking Buildbucket for the build status
[04:33:22.767] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:22.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:242:0) after 1m0s
[04:34:22.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:242:0)
[04:34:22.792] Timer tick, asking Buildbucket for the build status
[04:34:22.831] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:22.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:243:0) after 1m0s
[04:35:22.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:243:0)
[04:35:22.925] Timer tick, asking Buildbucket for the build status
[04:35:23.671] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:23.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:244:0) after 1m0s
[04:36:23.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:244:0)
[04:36:23.698] Timer tick, asking Buildbucket for the build status
[04:36:23.775] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:23.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:245:0) after 1m0s
[04:37:23.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:245:0)
[04:37:23.831] Timer tick, asking Buildbucket for the build status
[04:37:23.935] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:23.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:246:0) after 1m0s
[04:38:23.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:246:0)
[04:38:23.956] Timer tick, asking Buildbucket for the build status
[04:38:24.014] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:24.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:247:0) after 1m0s
[04:39:24.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:247:0)
[04:39:24.146] Timer tick, asking Buildbucket for the build status
[04:39:24.341] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:24.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:248:0) after 1m0s
[04:40:24.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:248:0)
[04:40:24.364] Timer tick, asking Buildbucket for the build status
[04:40:24.440] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:24.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:249:0) after 1m0s
[04:41:24.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:249:0)
[04:41:24.460] Timer tick, asking Buildbucket for the build status
[04:41:24.645] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:24.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:250:0) after 1m0s
[04:42:24.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:250:0)
[04:42:24.674] Timer tick, asking Buildbucket for the build status
[04:42:24.709] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:24.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:251:0) after 1m0s
[04:43:24.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:251:0)
[04:43:24.733] Timer tick, asking Buildbucket for the build status
[04:43:24.827] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:24.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:252:0) after 1m0s
[04:44:24.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:252:0)
[04:44:24.852] Timer tick, asking Buildbucket for the build status
[04:44:24.922] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:24.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:253:0) after 1m0s
[04:45:24.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:253:0)
[04:45:24.947] Timer tick, asking Buildbucket for the build status
[04:45:25.033] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:25.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:254:0) after 1m0s
[04:46:25.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:254:0)
[04:46:25.059] Timer tick, asking Buildbucket for the build status
[04:46:25.108] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:25.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:255:0) after 1m0s
[04:47:25.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:255:0)
[04:47:25.134] Timer tick, asking Buildbucket for the build status
[04:47:25.188] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:25.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:256:0) after 1m0s
[04:48:25.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:256:0)
[04:48:25.214] Timer tick, asking Buildbucket for the build status
[04:48:25.290] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:25.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:257:0) after 1m0s
[04:49:25.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:257:0)
[04:49:25.323] Timer tick, asking Buildbucket for the build status
[04:49:25.376] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:25.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:258:0) after 1m0s
[04:50:25.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:258:0)
[04:50:25.402] Timer tick, asking Buildbucket for the build status
[04:50:25.574] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:25.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:259:0) after 1m0s
[04:51:25.600] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:259:0)
[04:51:25.600] Timer tick, asking Buildbucket for the build status
[04:51:25.673] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:25.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:260:0) after 1m0s
[04:52:25.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:260:0)
[04:52:25.697] Timer tick, asking Buildbucket for the build status
[04:52:25.734] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:25.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:261:0) after 1m0s
[04:53:25.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:261:0)
[04:53:25.821] Timer tick, asking Buildbucket for the build status
[04:53:25.876] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:25.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:262:0) after 1m0s
[04:54:25.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:262:0)
[04:54:25.916] Timer tick, asking Buildbucket for the build status
[04:54:26.036] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:26.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:263:0) after 1m0s
[04:55:26.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:263:0)
[04:55:26.060] Timer tick, asking Buildbucket for the build status
[04:55:26.142] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:26.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:264:0) after 1m0s
[04:56:26.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:264:0)
[04:56:26.196] Timer tick, asking Buildbucket for the build status
[04:56:26.328] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:26.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:265:0) after 1m0s
[04:57:26.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:265:0)
[04:57:26.354] Timer tick, asking Buildbucket for the build status
[04:57:26.492] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:26.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:266:0) after 1m0s
[04:58:26.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:266:0)
[04:58:26.518] Timer tick, asking Buildbucket for the build status
[04:58:26.621] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:26.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:267:0) after 1m0s
[04:59:26.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:267:0)
[04:59:26.749] Timer tick, asking Buildbucket for the build status
[04:59:26.874] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:26.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:268:0) after 1m0s
[05:00:26.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:268:0)
[05:00:26.924] Timer tick, asking Buildbucket for the build status
[05:00:27.000] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:27.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:269:0) after 1m0s
[05:01:27.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:269:0)
[05:01:27.074] Timer tick, asking Buildbucket for the build status
[05:01:27.121] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:27.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:270:0) after 1m0s
[05:02:27.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:270:0)
[05:02:27.163] Timer tick, asking Buildbucket for the build status
[05:02:27.236] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:27.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:271:0) after 1m0s
[05:03:27.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:271:0)
[05:03:27.261] Timer tick, asking Buildbucket for the build status
[05:03:27.303] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:27.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:272:0) after 1m0s
[05:04:27.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:272:0)
[05:04:27.320] Timer tick, asking Buildbucket for the build status
[05:04:27.410] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:27.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:273:0) after 1m0s
[05:05:27.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:273:0)
[05:05:27.436] Timer tick, asking Buildbucket for the build status
[05:05:27.537] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:27.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:274:0) after 1m0s
[05:06:27.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:274:0)
[05:06:27.563] Timer tick, asking Buildbucket for the build status
[05:06:27.629] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:27.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:275:0) after 1m0s
[05:07:27.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:275:0)
[05:07:27.654] Timer tick, asking Buildbucket for the build status
[05:07:27.743] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:27.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:276:0) after 1m0s
[05:08:27.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:276:0)
[05:08:27.771] Timer tick, asking Buildbucket for the build status
[05:08:27.889] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:27.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:277:0) after 1m0s
[05:09:27.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:277:0)
[05:09:27.922] Timer tick, asking Buildbucket for the build status
[05:09:28.032] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:28.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:278:0) after 1m0s
[05:10:28.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:278:0)
[05:10:28.058] Timer tick, asking Buildbucket for the build status
[05:10:28.207] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:28.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:279:0) after 1m0s
[05:11:28.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:279:0)
[05:11:28.239] Timer tick, asking Buildbucket for the build status
[05:11:28.319] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:28.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:280:0) after 1m0s
[05:12:28.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:280:0)
[05:12:28.378] Timer tick, asking Buildbucket for the build status
[05:12:28.459] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:28.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:281:0) after 1m0s
[05:13:28.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:281:0)
[05:13:28.488] Timer tick, asking Buildbucket for the build status
[05:13:28.568] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:28.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:282:0) after 1m0s
[05:14:28.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:282:0)
[05:14:28.591] Timer tick, asking Buildbucket for the build status
[05:14:28.734] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:28.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:283:0) after 1m0s
[05:15:28.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:283:0)
[05:15:28.758] Timer tick, asking Buildbucket for the build status
[05:15:28.840] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:28.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:284:0) after 1m0s
[05:16:28.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:284:0)
[05:16:28.872] Timer tick, asking Buildbucket for the build status
[05:16:28.943] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:28.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:285:0) after 1m0s
[05:17:28.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:285:0)
[05:17:28.965] Timer tick, asking Buildbucket for the build status
[05:17:29.079] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:29.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:286:0) after 1m0s
[05:18:29.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:286:0)
[05:18:29.105] Timer tick, asking Buildbucket for the build status
[05:18:29.140] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:18:29.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:287:0) after 1m0s
[05:19:29.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:287:0)
[05:19:29.163] Timer tick, asking Buildbucket for the build status
[05:19:29.341] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:19:29.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:288:0) after 1m0s
[05:20:29.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:288:0)
[05:20:29.446] Timer tick, asking Buildbucket for the build status
[05:20:29.500] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:29.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:289:0) after 1m0s
[05:21:29.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:289:0)
[05:21:29.528] Timer tick, asking Buildbucket for the build status
[05:21:29.641] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:29.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:290:0) after 1m0s
[05:22:29.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:290:0)
[05:22:29.668] Timer tick, asking Buildbucket for the build status
[05:22:29.735] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:29.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:291:0) after 1m0s
[05:23:29.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:291:0)
[05:23:29.767] Timer tick, asking Buildbucket for the build status
[05:23:29.861] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:29.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:292:0) after 1m0s
[05:24:29.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:292:0)
[05:24:29.887] Timer tick, asking Buildbucket for the build status
[05:24:29.947] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:29.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:293:0) after 1m0s
[05:25:30.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:293:0)
[05:25:30.018] Timer tick, asking Buildbucket for the build status
[05:25:30.068] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:25:30.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:294:0) after 1m0s
[05:26:30.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:294:0)
[05:26:30.172] Timer tick, asking Buildbucket for the build status
[05:26:30.347] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:30.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:295:0) after 1m0s
[05:27:30.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:295:0)
[05:27:30.376] Timer tick, asking Buildbucket for the build status
[05:27:30.411] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:30.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:296:0) after 1m0s
[05:28:30.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:296:0)
[05:28:30.439] Timer tick, asking Buildbucket for the build status
[05:28:30.491] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:30.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:297:0) after 1m0s
[05:29:30.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:297:0)
[05:29:30.541] Timer tick, asking Buildbucket for the build status
[05:29:30.672] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:30.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:298:0) after 1m0s
[05:30:30.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:298:0)
[05:30:30.704] Timer tick, asking Buildbucket for the build status
[05:30:30.776] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:30.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:299:0) after 1m0s
[05:31:30.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:299:0)
[05:31:30.810] Timer tick, asking Buildbucket for the build status
[05:31:30.979] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:30.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:300:0) after 1m0s
[05:32:31.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:300:0)
[05:32:31.013] Timer tick, asking Buildbucket for the build status
[05:32:31.136] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:31.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:301:0) after 1m0s
[05:33:31.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:301:0)
[05:33:31.169] Timer tick, asking Buildbucket for the build status
[05:33:31.219] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:31.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:302:0) after 1m0s
[05:34:31.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:302:0)
[05:34:31.249] Timer tick, asking Buildbucket for the build status
[05:34:31.320] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:31.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:303:0) after 1m0s
[05:35:31.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:303:0)
[05:35:31.354] Timer tick, asking Buildbucket for the build status
[05:35:31.414] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:31.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:304:0) after 1m0s
[05:36:31.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:304:0)
[05:36:31.442] Timer tick, asking Buildbucket for the build status
[05:36:31.492] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:31.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:305:0) after 1m0s
[05:37:31.520] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:305:0)
[05:37:31.520] Timer tick, asking Buildbucket for the build status
[05:37:31.579] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:31.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:306:0) after 1m0s
[05:38:31.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:306:0)
[05:38:31.610] Timer tick, asking Buildbucket for the build status
[05:38:31.684] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:31.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:307:0) after 1m0s
[05:39:31.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:307:0)
[05:39:31.817] Timer tick, asking Buildbucket for the build status
[05:39:31.883] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:31.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:308:0) after 1m0s
[05:40:31.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:308:0)
[05:40:31.917] Timer tick, asking Buildbucket for the build status
[05:40:31.978] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:31.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:309:0) after 1m0s
[05:41:32.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:309:0)
[05:41:32.031] Timer tick, asking Buildbucket for the build status
[05:41:32.073] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:32.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:310:0) after 1m0s
[05:42:32.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:310:0)
[05:42:32.110] Timer tick, asking Buildbucket for the build status
[05:42:32.171] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:32.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:311:0) after 1m0s
[05:43:32.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:311:0)
[05:43:32.199] Timer tick, asking Buildbucket for the build status
[05:43:32.364] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:32.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:312:0) after 1m0s
[05:44:32.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:312:0)
[05:44:32.395] Timer tick, asking Buildbucket for the build status
[05:44:32.466] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:32.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:313:0) after 1m0s
[05:45:32.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:313:0)
[05:45:32.490] Timer tick, asking Buildbucket for the build status
[05:45:32.530] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:32.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:314:0) after 1m0s
[05:46:32.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:314:0)
[05:46:32.570] Timer tick, asking Buildbucket for the build status
[05:46:32.654] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:32.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:315:0) after 1m0s
[05:47:32.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:315:0)
[05:47:32.678] Timer tick, asking Buildbucket for the build status
[05:47:32.818] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:32.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:316:0) after 1m0s
[05:48:32.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:316:0)
[05:48:32.876] Timer tick, asking Buildbucket for the build status
[05:48:32.947] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:32.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:317:0) after 1m0s
[05:49:32.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:317:0)
[05:49:32.992] Timer tick, asking Buildbucket for the build status
[05:49:33.031] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:33.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:318:0) after 1m0s
[05:50:33.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:318:0)
[05:50:33.078] Timer tick, asking Buildbucket for the build status
[05:50:33.147] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:33.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:319:0) after 1m0s
[05:51:33.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:319:0)
[05:51:33.177] Timer tick, asking Buildbucket for the build status
[05:51:33.245] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:33.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:320:0) after 1m0s
[05:52:33.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:320:0)
[05:52:33.290] Timer tick, asking Buildbucket for the build status
[05:52:33.455] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:33.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:321:0) after 1m0s
[05:53:33.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:321:0)
[05:53:33.483] Timer tick, asking Buildbucket for the build status
[05:53:33.532] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:33.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:322:0) after 1m0s
[05:54:33.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:322:0)
[05:54:33.564] Timer tick, asking Buildbucket for the build status
[05:54:33.637] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:33.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:323:0) after 1m0s
[05:55:33.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:323:0)
[05:55:33.668] Timer tick, asking Buildbucket for the build status
[05:55:33.863] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:33.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:324:0) after 1m0s
[05:56:33.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:324:0)
[05:56:33.902] Timer tick, asking Buildbucket for the build status
[05:56:33.985] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:33.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:325:0) after 1m0s
[05:57:34.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:325:0)
[05:57:34.079] Timer tick, asking Buildbucket for the build status
[05:57:34.146] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:34.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:326:0) after 1m0s
[05:58:34.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:326:0)
[05:58:34.182] Timer tick, asking Buildbucket for the build status
[05:58:34.226] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:34.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:327:0) after 1m0s
[05:59:34.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:327:0)
[05:59:34.252] Timer tick, asking Buildbucket for the build status
[05:59:34.431] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:34.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:328:0) after 1m0s
[06:00:34.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:328:0)
[06:00:34.457] Timer tick, asking Buildbucket for the build status
[06:00:34.521] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:34.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:329:0) after 1m0s
[06:01:34.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:329:0)
[06:01:34.570] Timer tick, asking Buildbucket for the build status
[06:01:34.637] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:34.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:330:0) after 1m0s
[06:02:34.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:330:0)
[06:02:34.670] Timer tick, asking Buildbucket for the build status
[06:02:34.882] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:34.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:331:0) after 1m0s
[06:03:34.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:331:0)
[06:03:34.920] Timer tick, asking Buildbucket for the build status
[06:03:35.036] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:35.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:332:0) after 1m0s
[06:04:35.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:332:0)
[06:04:35.065] Timer tick, asking Buildbucket for the build status
[06:04:35.131] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:35.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:333:0) after 1m0s
[06:05:35.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:333:0)
[06:05:35.159] Timer tick, asking Buildbucket for the build status
[06:05:35.249] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:35.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:334:0) after 1m0s
[06:06:35.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:334:0)
[06:06:35.316] Timer tick, asking Buildbucket for the build status
[06:06:35.383] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:35.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:335:0) after 1m0s
[06:07:35.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:335:0)
[06:07:35.428] Timer tick, asking Buildbucket for the build status
[06:07:35.521] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:35.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:336:0) after 1m0s
[06:08:35.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:336:0)
[06:08:35.573] Timer tick, asking Buildbucket for the build status
[06:08:35.724] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:35.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:337:0) after 1m0s
[06:09:35.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:337:0)
[06:09:35.752] Timer tick, asking Buildbucket for the build status
[06:09:35.834] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:35.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:338:0) after 1m0s
[06:10:35.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:338:0)
[06:10:35.921] Timer tick, asking Buildbucket for the build status
[06:10:35.984] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:35.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:339:0) after 1m0s
[06:11:36.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:339:0)
[06:11:36.021] Timer tick, asking Buildbucket for the build status
[06:11:36.244] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:36.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:340:0) after 1m0s
[06:12:36.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:340:0)
[06:12:36.337] Timer tick, asking Buildbucket for the build status
[06:12:36.504] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:36.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:341:0) after 1m0s
[06:13:36.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:341:0)
[06:13:36.539] Timer tick, asking Buildbucket for the build status
[06:13:36.619] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:36.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:342:0) after 1m0s
[06:14:36.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:342:0)
[06:14:36.650] Timer tick, asking Buildbucket for the build status
[06:14:36.737] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:36.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:343:0) after 1m0s
[06:15:36.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:343:0)
[06:15:36.764] Timer tick, asking Buildbucket for the build status
[06:15:36.845] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:36.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:344:0) after 1m0s
[06:16:36.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:344:0)
[06:16:36.876] Timer tick, asking Buildbucket for the build status
[06:16:37.017] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:37.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:345:0) after 1m0s
[06:17:37.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:345:0)
[06:17:37.047] Timer tick, asking Buildbucket for the build status
[06:17:37.248] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:37.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:346:0) after 1m0s
[06:18:37.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:346:0)
[06:18:37.286] Timer tick, asking Buildbucket for the build status
[06:18:37.531] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:37.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:347:0) after 1m0s
[06:19:37.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:347:0)
[06:19:37.563] Timer tick, asking Buildbucket for the build status
[06:19:37.615] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:37.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:348:0) after 1m0s
[06:20:37.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:348:0)
[06:20:37.648] Timer tick, asking Buildbucket for the build status
[06:20:37.694] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:37.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:349:0) after 1m0s
[06:21:37.739] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:349:0)
[06:21:37.739] Timer tick, asking Buildbucket for the build status
[06:21:37.876] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:37.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:350:0) after 1m0s
[06:22:37.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:350:0)
[06:22:37.902] Timer tick, asking Buildbucket for the build status
[06:22:37.962] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:37.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:351:0) after 1m0s
[06:23:37.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:351:0)
[06:23:37.992] Timer tick, asking Buildbucket for the build status
[06:23:38.051] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:38.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:352:0) after 1m0s
[06:24:38.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:352:0)
[06:24:38.127] Timer tick, asking Buildbucket for the build status
[06:24:38.204] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:38.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:353:0) after 1m0s
[06:25:38.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:353:0)
[06:25:38.234] Timer tick, asking Buildbucket for the build status
[06:25:38.287] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:38.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:354:0) after 1m0s
[06:26:38.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:354:0)
[06:26:38.338] Timer tick, asking Buildbucket for the build status
[06:26:38.497] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:38.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:355:0) after 1m0s
[06:27:38.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:355:0)
[06:27:38.532] Timer tick, asking Buildbucket for the build status
[06:27:38.574] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:38.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:356:0) after 1m0s
[06:28:38.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:356:0)
[06:28:38.603] Timer tick, asking Buildbucket for the build status
[06:28:38.729] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:38.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:357:0) after 1m0s
[06:29:38.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:357:0)
[06:29:38.778] Timer tick, asking Buildbucket for the build status
[06:29:38.981] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:38.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:358:0) after 1m0s
[06:30:39.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:358:0)
[06:30:39.009] Timer tick, asking Buildbucket for the build status
[06:30:39.142] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:39.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:359:0) after 1m0s
[06:31:39.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:359:0)
[06:31:39.182] Timer tick, asking Buildbucket for the build status
[06:31:39.318] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:39.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:360:0) after 1m0s
[06:32:39.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:360:0)
[06:32:39.351] Timer tick, asking Buildbucket for the build status
[06:32:39.446] Build 8924037595096141024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:39.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9089467657445892240:361:0) after 1m0s
[06:33:03.923] Received PubSub notification, asking Buildbucket for the build status
[06:33:03.962] Build 8924037595096141024: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[06:33:03.962] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "cancelation_reason": "TIMEOUT",
    "completed_ts": "1547793183358256",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1547771569121258",
    "id": "8924037595096141024",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"db355f48151995834fbdddd464fc00b8ad265132\"}}",
    "project": "nacl",
    "result": "CANCELED",
    "result_details_json": "{\"swarming\": {\"bot_dimensions\": {}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "COMPLETED",
    "status_changed_ts": "1547793183652364",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7019",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/db355f48151995834fbdddd464fc00b8ad265132",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db355f48151995834fbdddd464fc00b8ad265132",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9089467657445892240",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_dimension:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7019",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8924037595096141024",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64",
      "swarming_tag:buildset:commit/git/db355f48151995834fbdddd464fc00b8ad265132",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/db355f48151995834fbdddd464fc00b8ad265132",
      "swarming_tag:caches:builder_eacc41f1df5d043e03eef0198bc8ddecd24b0492a666afd70585f546f0146c16_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8924037595096141024/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9089467657445892240",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:7bd44c5698750454da45e03e17f93c16ef58f23d",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4277316e99765010",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1547793183653062",
    "url": "https://ci.chromium.org/b/8924037595096141024",
    "utcnow_ts": "1547793183953659"
  }
}
[06:33:03.962] Invocation finished in 6h0m16.46679617s with status FAILED