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

• 952e81813a66902714b68f34b51073c5d1ba6c94 (4 years ago)

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

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

[11:26:48.213] New invocation is queued and will start shortly
[11:26:49.423] Starting the invocation (attempt 1)
[11:26:49.471] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:26:49.474] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:26:49.474] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9021931770624335120",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\",\"title\":\"952e81813a66902714b68f34b51073c5d1ba6c94\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
  "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:9021931770624335120",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
    "gitiles_ref:refs/heads/master"
  ]
}
[11:26:50.318] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1612178809600908",
    "id": "8856501708502601168",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1612178810136692",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7085",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021931770624335120",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856501708502601168/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1612178810136884",
    "url": "https://ci.chromium.org/b/8856501708502601168",
    "utcnow_ts": "1612178810305944"
  }
}
[11:26:50.318] Task URL: https://ci.chromium.org/b/8856501708502601168
[11:26:50.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:2:0) after 1m0s
[11:27:50.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:2:0)
[11:27:50.984] Timer tick, asking Buildbucket for the build status
[11:27:51.244] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:51.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:3:0) after 1m0s
[11:28:51.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:3:0)
[11:28:51.348] Timer tick, asking Buildbucket for the build status
[11:28:51.446] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:51.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:4:0) after 1m0s
[11:29:51.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:4:0)
[11:29:51.526] Timer tick, asking Buildbucket for the build status
[11:29:51.626] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:51.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:5:0) after 1m0s
[11:30:51.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:5:0)
[11:30:51.712] Timer tick, asking Buildbucket for the build status
[11:30:51.925] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:51.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:6:0) after 1m0s
[11:31:51.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:6:0)
[11:31:51.982] Timer tick, asking Buildbucket for the build status
[11:31:52.282] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:52.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:7:0) after 1m0s
[11:32:52.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:7:0)
[11:32:52.334] Timer tick, asking Buildbucket for the build status
[11:32:52.408] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:52.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:8:0) after 1m0s
[11:33:52.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:8:0)
[11:33:52.687] Timer tick, asking Buildbucket for the build status
[11:33:52.805] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:52.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:9:0) after 1m0s
[11:34:52.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:9:0)
[11:34:52.892] Timer tick, asking Buildbucket for the build status
[11:34:52.973] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:34:52.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:10:0) after 1m0s
[11:35:53.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:10:0)
[11:35:53.174] Timer tick, asking Buildbucket for the build status
[11:35:53.398] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:35:53.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:11:0) after 1m0s
[11:36:53.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:11:0)
[11:36:53.877] Timer tick, asking Buildbucket for the build status
[11:36:54.048] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:36:54.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:12:0) after 1m0s
[11:37:54.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:12:0)
[11:37:54.132] Timer tick, asking Buildbucket for the build status
[11:37:54.404] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:54.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:13:0) after 1m0s
[11:38:54.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:13:0)
[11:38:54.523] Timer tick, asking Buildbucket for the build status
[11:38:54.825] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:54.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:14:0) after 1m0s
[11:39:54.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:14:0)
[11:39:54.936] Timer tick, asking Buildbucket for the build status
[11:39:55.032] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:55.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:15:0) after 1m0s
[11:40:55.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:15:0)
[11:40:55.055] Timer tick, asking Buildbucket for the build status
[11:40:55.170] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:55.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:16:0) after 1m0s
[11:41:55.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:16:0)
[11:41:55.162] Timer tick, asking Buildbucket for the build status
[11:41:55.754] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:55.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:17:0) after 1m0s
[11:42:55.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:17:0)
[11:42:55.745] Timer tick, asking Buildbucket for the build status
[11:42:55.827] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:55.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:18:0) after 1m0s
[11:43:55.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:18:0)
[11:43:55.963] Timer tick, asking Buildbucket for the build status
[11:43:56.126] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:56.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:19:0) after 1m0s
[11:44:56.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:19:0)
[11:44:56.292] Timer tick, asking Buildbucket for the build status
[11:44:56.484] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:56.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:20:0) after 1m0s
[11:45:56.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:20:0)
[11:45:56.528] Timer tick, asking Buildbucket for the build status
[11:45:56.640] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:56.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:21:0) after 1m0s
[11:46:56.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:21:0)
[11:46:56.692] Timer tick, asking Buildbucket for the build status
[11:46:56.790] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:56.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:22:0) after 1m0s
[11:47:56.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:22:0)
[11:47:56.829] Timer tick, asking Buildbucket for the build status
[11:47:56.917] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:56.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:23:0) after 1m0s
[11:48:56.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:23:0)
[11:48:56.941] Timer tick, asking Buildbucket for the build status
[11:48:57.362] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:57.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:24:0) after 1m0s
[11:49:57.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:24:0)
[11:49:57.351] Timer tick, asking Buildbucket for the build status
[11:49:57.536] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:57.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:25:0) after 1m0s
[11:50:57.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:25:0)
[11:50:57.577] Timer tick, asking Buildbucket for the build status
[11:50:57.651] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:57.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:26:0) after 1m0s
[11:51:57.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:26:0)
[11:51:57.759] Timer tick, asking Buildbucket for the build status
[11:51:58.732] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:51:58.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:27:0) after 1m0s
[11:52:58.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:27:0)
[11:52:58.862] Timer tick, asking Buildbucket for the build status
[11:52:59.048] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:59.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:28:0) after 1m0s
[11:53:59.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:28:0)
[11:53:59.067] Timer tick, asking Buildbucket for the build status
[11:53:59.439] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:53:59.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:29:0) after 1m0s
[11:54:59.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:29:0)
[11:54:59.428] Timer tick, asking Buildbucket for the build status
[11:54:59.518] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:54:59.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:30:0) after 1m0s
[11:55:59.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:30:0)
[11:55:59.585] Timer tick, asking Buildbucket for the build status
[11:55:59.842] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:59.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:31:0) after 1m0s
[11:56:59.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:31:0)
[11:56:59.913] Timer tick, asking Buildbucket for the build status
[11:57:00.086] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:00.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:32:0) after 1m0s
[11:58:00.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:32:0)
[11:58:00.738] Timer tick, asking Buildbucket for the build status
[11:58:01.251] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:01.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:33:0) after 1m0s
[11:59:01.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:33:0)
[11:59:01.322] Timer tick, asking Buildbucket for the build status
[11:59:01.405] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:01.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:34:0) after 1m0s
[12:00:01.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:34:0)
[12:00:01.409] Timer tick, asking Buildbucket for the build status
[12:00:01.575] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:01.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:35:0) after 1m0s
[12:01:01.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:35:0)
[12:01:01.572] Timer tick, asking Buildbucket for the build status
[12:01:01.678] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:01.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:36:0) after 1m0s
[12:02:01.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:36:0)
[12:02:01.729] Timer tick, asking Buildbucket for the build status
[12:02:01.871] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:01.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:37:0) after 1m0s
[12:03:01.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:37:0)
[12:03:01.895] Timer tick, asking Buildbucket for the build status
[12:03:02.102] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:02.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:38:0) after 1m0s
[12:04:02.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:38:0)
[12:04:02.114] Timer tick, asking Buildbucket for the build status
[12:04:02.248] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:02.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:39:0) after 1m0s
[12:05:02.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:39:0)
[12:05:02.313] Timer tick, asking Buildbucket for the build status
[12:05:02.435] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:02.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:40:0) after 1m0s
[12:06:02.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:40:0)
[12:06:02.483] Timer tick, asking Buildbucket for the build status
[12:06:02.559] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:06:02.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:41:0) after 1m0s
[12:07:02.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:41:0)
[12:07:02.638] Timer tick, asking Buildbucket for the build status
[12:07:02.779] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:07:02.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:42:0) after 1m0s
[12:08:02.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:42:0)
[12:08:02.878] Timer tick, asking Buildbucket for the build status
[12:08:02.955] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:02.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:43:0) after 1m0s
[12:09:02.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:43:0)
[12:09:02.987] Timer tick, asking Buildbucket for the build status
[12:09:03.237] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:03.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:44:0) after 1m0s
[12:10:03.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:44:0)
[12:10:03.211] Timer tick, asking Buildbucket for the build status
[12:10:03.437] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:03.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:45:0) after 1m0s
[12:11:03.485] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:45:0)
[12:11:03.485] Timer tick, asking Buildbucket for the build status
[12:11:03.594] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:03.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:46:0) after 1m0s
[12:12:03.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:46:0)
[12:12:03.640] Timer tick, asking Buildbucket for the build status
[12:12:03.770] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:03.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:47:0) after 1m0s
[12:13:03.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:47:0)
[12:13:03.773] Timer tick, asking Buildbucket for the build status
[12:13:04.014] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:04.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:48:0) after 1m0s
[12:14:04.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:48:0)
[12:14:04.083] Timer tick, asking Buildbucket for the build status
[12:14:04.183] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:04.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:49:0) after 1m0s
[12:15:04.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:49:0)
[12:15:04.237] Timer tick, asking Buildbucket for the build status
[12:15:04.738] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:04.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:50:0) after 1m0s
[12:16:04.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:50:0)
[12:16:04.735] Timer tick, asking Buildbucket for the build status
[12:16:04.823] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:04.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:51:0) after 1m0s
[12:17:04.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:51:0)
[12:17:04.920] Timer tick, asking Buildbucket for the build status
[12:17:05.114] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:05.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:52:0) after 1m0s
[12:18:05.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:52:0)
[12:18:05.211] Timer tick, asking Buildbucket for the build status
[12:18:05.313] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:05.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:53:0) after 1m0s
[12:19:05.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:53:0)
[12:19:05.402] Timer tick, asking Buildbucket for the build status
[12:19:06.350] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:06.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:54:0) after 1m0s
[12:20:06.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:54:0)
[12:20:06.364] Timer tick, asking Buildbucket for the build status
[12:20:06.458] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:06.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:55:0) after 1m0s
[12:21:06.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:55:0)
[12:21:06.501] Timer tick, asking Buildbucket for the build status
[12:21:06.609] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:06.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:56:0) after 1m0s
[12:22:06.629] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:56:0)
[12:22:06.629] Timer tick, asking Buildbucket for the build status
[12:22:06.768] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:06.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:57:0) after 1m0s
[12:23:06.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:57:0)
[12:23:06.844] Timer tick, asking Buildbucket for the build status
[12:23:07.145] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:07.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:58:0) after 1m0s
[12:24:07.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:58:0)
[12:24:07.238] Timer tick, asking Buildbucket for the build status
[12:24:07.336] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:07.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:59:0) after 1m0s
[12:25:07.407] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:59:0)
[12:25:07.407] Timer tick, asking Buildbucket for the build status
[12:25:07.482] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:07.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:60:0) after 1m0s
[12:26:07.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:60:0)
[12:26:07.605] Timer tick, asking Buildbucket for the build status
[12:26:07.804] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:07.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:61:0) after 1m0s
[12:27:07.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:61:0)
[12:27:07.894] Timer tick, asking Buildbucket for the build status
[12:27:08.374] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:08.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:62:0) after 1m0s
[12:28:08.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:62:0)
[12:28:08.942] Timer tick, asking Buildbucket for the build status
[12:28:09.160] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:28:09.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:63:0) after 1m0s
[12:29:09.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:63:0)
[12:29:09.224] Timer tick, asking Buildbucket for the build status
[12:29:09.516] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:29:09.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:64:0) after 1m0s
[12:30:09.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:64:0)
[12:30:09.557] Timer tick, asking Buildbucket for the build status
[12:30:09.630] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:30:09.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:65:0) after 1m0s
[12:31:09.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:65:0)
[12:31:09.684] Timer tick, asking Buildbucket for the build status
[12:31:09.754] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:09.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:66:0) after 1m0s
[12:32:09.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:66:0)
[12:32:09.845] Timer tick, asking Buildbucket for the build status
[12:32:09.940] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:09.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:67:0) after 1m0s
[12:33:09.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:67:0)
[12:33:09.980] Timer tick, asking Buildbucket for the build status
[12:33:10.073] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:10.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:68:0) after 1m0s
[12:34:10.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:68:0)
[12:34:10.174] Timer tick, asking Buildbucket for the build status
[12:34:10.267] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:10.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:69:0) after 1m0s
[12:35:10.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:69:0)
[12:35:10.292] Timer tick, asking Buildbucket for the build status
[12:35:11.085] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:11.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:70:0) after 1m0s
[12:36:11.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:70:0)
[12:36:11.320] Timer tick, asking Buildbucket for the build status
[12:36:11.459] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:11.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:71:0) after 1m0s
[12:37:11.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:71:0)
[12:37:11.585] Timer tick, asking Buildbucket for the build status
[12:37:11.740] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:11.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:72:0) after 1m0s
[12:38:11.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:72:0)
[12:38:11.810] Timer tick, asking Buildbucket for the build status
[12:38:12.009] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:12.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:73:0) after 1m0s
[12:39:12.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:73:0)
[12:39:12.046] Timer tick, asking Buildbucket for the build status
[12:39:12.337] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:12.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:74:0) after 1m0s
[12:40:12.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:74:0)
[12:40:12.387] Timer tick, asking Buildbucket for the build status
[12:40:12.701] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:12.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:75:0) after 1m0s
[12:41:12.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:75:0)
[12:41:12.836] Timer tick, asking Buildbucket for the build status
[12:41:12.952] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:12.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:76:0) after 1m0s
[12:42:13.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:76:0)
[12:42:13.043] Timer tick, asking Buildbucket for the build status
[12:42:13.493] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:13.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:77:0) after 1m0s
[12:43:13.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:77:0)
[12:43:13.578] Timer tick, asking Buildbucket for the build status
[12:43:13.652] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:13.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:78:0) after 1m0s
[12:44:13.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:78:0)
[12:44:13.658] Timer tick, asking Buildbucket for the build status
[12:44:13.875] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:13.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:79:0) after 1m0s
[12:45:13.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:79:0)
[12:45:13.920] Timer tick, asking Buildbucket for the build status
[12:45:14.140] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:14.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:80:0) after 1m0s
[12:46:14.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:80:0)
[12:46:14.270] Timer tick, asking Buildbucket for the build status
[12:46:14.441] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:14.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:81:0) after 1m0s
[12:47:14.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:81:0)
[12:47:14.469] Timer tick, asking Buildbucket for the build status
[12:47:14.833] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:14.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:82:0) after 1m0s
[12:48:14.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:82:0)
[12:48:14.922] Timer tick, asking Buildbucket for the build status
[12:48:15.003] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:15.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:83:0) after 1m0s
[12:49:14.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:83:0)
[12:49:14.988] Timer tick, asking Buildbucket for the build status
[12:49:15.058] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:15.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:84:0) after 1m0s
[12:50:15.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:84:0)
[12:50:15.065] Timer tick, asking Buildbucket for the build status
[12:50:15.160] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:15.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:85:0) after 1m0s
[12:51:15.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:85:0)
[12:51:15.203] Timer tick, asking Buildbucket for the build status
[12:51:16.316] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:16.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:86:0) after 1m0s
[12:52:16.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:86:0)
[12:52:16.395] Timer tick, asking Buildbucket for the build status
[12:52:16.505] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:16.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:87:0) after 1m0s
[12:53:16.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:87:0)
[12:53:16.560] Timer tick, asking Buildbucket for the build status
[12:53:22.556] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:22.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:88:0) after 1m0s
[12:54:22.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:88:0)
[12:54:22.839] Timer tick, asking Buildbucket for the build status
[12:54:22.918] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:22.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:89:0) after 1m0s
[12:55:23.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:89:0)
[12:55:23.041] Timer tick, asking Buildbucket for the build status
[12:55:23.317] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:23.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:90:0) after 1m0s
[12:56:23.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:90:0)
[12:56:23.420] Timer tick, asking Buildbucket for the build status
[12:56:23.537] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:23.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:91:0) after 1m0s
[12:57:23.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:91:0)
[12:57:23.655] Timer tick, asking Buildbucket for the build status
[12:57:23.750] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:23.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:92:0) after 1m0s
[12:58:23.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:92:0)
[12:58:23.905] Timer tick, asking Buildbucket for the build status
[12:58:24.064] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:24.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:93:0) after 1m0s
[12:59:24.136] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:93:0)
[12:59:24.136] Timer tick, asking Buildbucket for the build status
[12:59:24.207] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:24.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:94:0) after 1m0s
[13:00:24.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:94:0)
[13:00:24.351] Timer tick, asking Buildbucket for the build status
[13:00:24.415] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:24.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:95:0) after 1m0s
[13:01:24.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:95:0)
[13:01:24.495] Timer tick, asking Buildbucket for the build status
[13:01:24.578] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:24.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:96:0) after 1m0s
[13:02:24.710] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:96:0)
[13:02:24.710] Timer tick, asking Buildbucket for the build status
[13:02:25.106] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:25.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:97:0) after 1m0s
[13:03:25.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:97:0)
[13:03:25.161] Timer tick, asking Buildbucket for the build status
[13:03:25.638] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:25.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:98:0) after 1m0s
[13:04:25.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:98:0)
[13:04:25.687] Timer tick, asking Buildbucket for the build status
[13:04:25.775] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:25.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:99:0) after 1m0s
[13:05:25.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:99:0)
[13:05:25.787] Timer tick, asking Buildbucket for the build status
[13:05:26.015] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:26.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:100:0) after 1m0s
[13:06:26.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:100:0)
[13:06:26.140] Timer tick, asking Buildbucket for the build status
[13:06:26.237] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:26.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:101:0) after 1m0s
[13:07:26.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:101:0)
[13:07:26.265] Timer tick, asking Buildbucket for the build status
[13:07:26.664] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:26.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:102:0) after 1m0s
[13:08:26.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:102:0)
[13:08:26.731] Timer tick, asking Buildbucket for the build status
[13:08:26.841] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:26.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:103:0) after 1m0s
[13:09:26.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:103:0)
[13:09:26.891] Timer tick, asking Buildbucket for the build status
[13:09:26.966] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:26.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:104:0) after 1m0s
[13:10:26.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:104:0)
[13:10:26.949] Timer tick, asking Buildbucket for the build status
[13:10:27.066] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:27.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:105:0) after 1m0s
[13:11:27.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:105:0)
[13:11:27.114] Timer tick, asking Buildbucket for the build status
[13:11:27.296] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:27.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:106:0) after 1m0s
[13:12:27.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:106:0)
[13:12:27.340] Timer tick, asking Buildbucket for the build status
[13:12:27.697] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:27.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:107:0) after 1m0s
[13:13:27.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:107:0)
[13:13:27.735] Timer tick, asking Buildbucket for the build status
[13:13:27.845] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:27.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:108:0) after 1m0s
[13:14:27.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:108:0)
[13:14:27.944] Timer tick, asking Buildbucket for the build status
[13:14:28.085] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:28.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:109:0) after 1m0s
[13:15:28.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:109:0)
[13:15:28.247] Timer tick, asking Buildbucket for the build status
[13:15:28.345] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:28.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:110:0) after 1m0s
[13:16:28.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:110:0)
[13:16:28.345] Timer tick, asking Buildbucket for the build status
[13:16:28.417] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:28.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:111:0) after 1m0s
[13:17:28.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:111:0)
[13:17:28.452] Timer tick, asking Buildbucket for the build status
[13:17:28.563] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:28.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:112:0) after 1m0s
[13:18:28.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:112:0)
[13:18:28.651] Timer tick, asking Buildbucket for the build status
[13:18:28.731] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:28.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:113:0) after 1m0s
[13:19:28.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:113:0)
[13:19:28.870] Timer tick, asking Buildbucket for the build status
[13:19:28.966] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:28.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:114:0) after 1m0s
[13:20:28.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:114:0)
[13:20:28.989] Timer tick, asking Buildbucket for the build status
[13:20:29.221] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:29.221] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:115:0) after 1m0s
[13:21:29.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:115:0)
[13:21:29.338] Timer tick, asking Buildbucket for the build status
[13:21:29.457] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:29.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:116:0) after 1m0s
[13:22:29.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:116:0)
[13:22:29.673] Timer tick, asking Buildbucket for the build status
[13:22:29.836] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:29.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:117:0) after 1m0s
[13:23:31.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:117:0)
[13:23:31.845] Timer tick, asking Buildbucket for the build status
[13:23:31.954] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:31.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:118:0) after 1m0s
[13:24:32.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:118:0)
[13:24:32.001] Timer tick, asking Buildbucket for the build status
[13:24:32.083] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:32.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:119:0) after 1m0s
[13:25:32.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:119:0)
[13:25:32.161] Timer tick, asking Buildbucket for the build status
[13:25:32.248] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:32.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:120:0) after 1m0s
[13:26:32.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:120:0)
[13:26:32.345] Timer tick, asking Buildbucket for the build status
[13:26:32.610] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:32.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:121:0) after 1m0s
[13:27:32.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:121:0)
[13:27:32.799] Timer tick, asking Buildbucket for the build status
[13:27:32.932] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:32.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:122:0) after 1m0s
[13:28:33.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:122:0)
[13:28:33.054] Timer tick, asking Buildbucket for the build status
[13:28:33.240] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:33.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:123:0) after 1m0s
[13:29:33.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:123:0)
[13:29:33.297] Timer tick, asking Buildbucket for the build status
[13:29:33.384] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:33.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:124:0) after 1m0s
[13:30:33.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:124:0)
[13:30:33.454] Timer tick, asking Buildbucket for the build status
[13:30:33.540] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:33.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:125:0) after 1m0s
[13:31:33.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:125:0)
[13:31:33.616] Timer tick, asking Buildbucket for the build status
[13:31:33.711] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:33.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:126:0) after 1m0s
[13:32:33.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:126:0)
[13:32:33.744] Timer tick, asking Buildbucket for the build status
[13:32:33.851] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:33.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:127:0) after 1m0s
[13:33:33.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:127:0)
[13:33:33.956] Timer tick, asking Buildbucket for the build status
[13:33:38.417] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:38.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:128:0) after 1m0s
[13:34:38.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:128:0)
[13:34:38.532] Timer tick, asking Buildbucket for the build status
[13:34:38.652] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:38.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:129:0) after 1m0s
[13:35:38.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:129:0)
[13:35:38.787] Timer tick, asking Buildbucket for the build status
[13:35:39.037] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:39.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:130:0) after 1m0s
[13:36:39.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:130:0)
[13:36:39.107] Timer tick, asking Buildbucket for the build status
[13:36:39.275] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:39.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:131:0) after 1m0s
[13:37:39.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:131:0)
[13:37:39.355] Timer tick, asking Buildbucket for the build status
[13:37:39.638] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:39.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:132:0) after 1m0s
[13:38:39.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:132:0)
[13:38:39.935] Timer tick, asking Buildbucket for the build status
[13:38:40.035] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:40.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:133:0) after 1m0s
[13:39:40.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:133:0)
[13:39:40.039] Timer tick, asking Buildbucket for the build status
[13:39:40.138] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:40.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:134:0) after 1m0s
[13:40:40.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:134:0)
[13:40:40.182] Timer tick, asking Buildbucket for the build status
[13:40:40.259] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:40.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:135:0) after 1m0s
[13:41:40.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:135:0)
[13:41:40.419] Timer tick, asking Buildbucket for the build status
[13:41:40.489] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:40.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:136:0) after 1m0s
[13:42:40.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:136:0)
[13:42:40.595] Timer tick, asking Buildbucket for the build status
[13:42:40.704] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:40.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:137:0) after 1m0s
[13:43:40.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:137:0)
[13:43:40.839] Timer tick, asking Buildbucket for the build status
[13:43:40.920] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:40.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:138:0) after 1m0s
[13:44:41.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:138:0)
[13:44:41.145] Timer tick, asking Buildbucket for the build status
[13:44:41.218] Build 8856501708502601168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:41.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:139:0) after 1m0s
[13:45:06.492] Received PubSub notification, asking Buildbucket for the build status
[13:45:06.707] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:41.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:139:0)
[13:45:41.411] Timer tick, asking Buildbucket for the build status
[13:45:41.588] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:41.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:141:0) after 1m0s
[13:46:41.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:141:0)
[13:46:41.627] Timer tick, asking Buildbucket for the build status
[13:46:41.750] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:41.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:142:0) after 1m0s
[13:47:41.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:142:0)
[13:47:41.910] Timer tick, asking Buildbucket for the build status
[13:47:43.179] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:43.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:143:0) after 1m0s
[13:48:43.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:143:0)
[13:48:43.294] Timer tick, asking Buildbucket for the build status
[13:48:43.717] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:43.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:144:0) after 1m0s
[13:49:43.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:144:0)
[13:49:43.717] Timer tick, asking Buildbucket for the build status
[13:49:43.867] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:43.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:145:0) after 1m0s
[13:50:43.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:145:0)
[13:50:43.916] Timer tick, asking Buildbucket for the build status
[13:50:43.992] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:43.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:146:0) after 1m0s
[13:51:44.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:146:0)
[13:51:44.047] Timer tick, asking Buildbucket for the build status
[13:51:44.379] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:44.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:147:0) after 1m0s
[13:52:44.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:147:0)
[13:52:44.481] Timer tick, asking Buildbucket for the build status
[13:52:44.668] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:44.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:148:0) after 1m0s
[13:53:44.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:148:0)
[13:53:44.777] Timer tick, asking Buildbucket for the build status
[13:53:44.942] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:44.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:149:0) after 1m0s
[13:54:44.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:149:0)
[13:54:44.956] Timer tick, asking Buildbucket for the build status
[13:54:45.139] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:45.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:150:0) after 1m0s
[13:55:45.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:150:0)
[13:55:45.381] Timer tick, asking Buildbucket for the build status
[13:55:45.453] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:45.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:151:0) after 1m0s
[13:56:45.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:151:0)
[13:56:45.730] Timer tick, asking Buildbucket for the build status
[13:56:45.793] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:45.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:152:0) after 1m0s
[13:57:45.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:152:0)
[13:57:45.845] Timer tick, asking Buildbucket for the build status
[13:57:46.314] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:46.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:153:0) after 1m0s
[13:58:46.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:153:0)
[13:58:46.306] Timer tick, asking Buildbucket for the build status
[13:58:46.391] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:46.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:154:0) after 1m0s
[13:59:46.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:154:0)
[13:59:46.458] Timer tick, asking Buildbucket for the build status
[13:59:46.708] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:46.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:155:0) after 1m0s
[14:00:46.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:155:0)
[14:00:46.849] Timer tick, asking Buildbucket for the build status
[14:00:47.019] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:47.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:156:0) after 1m0s
[14:01:47.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:156:0)
[14:01:47.053] Timer tick, asking Buildbucket for the build status
[14:01:47.145] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:47.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:157:0) after 1m0s
[14:02:47.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:157:0)
[14:02:47.317] Timer tick, asking Buildbucket for the build status
[14:02:47.726] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:47.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:158:0) after 1m0s
[14:03:47.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:158:0)
[14:03:47.930] Timer tick, asking Buildbucket for the build status
[14:03:48.013] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:48.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:159:0) after 1m0s
[14:04:48.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:159:0)
[14:04:48.097] Timer tick, asking Buildbucket for the build status
[14:04:48.186] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:48.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:160:0) after 1m0s
[14:05:48.315] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:160:0)
[14:05:48.315] Timer tick, asking Buildbucket for the build status
[14:05:48.543] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:48.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:161:0) after 1m0s
[14:06:48.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:161:0)
[14:06:48.587] Timer tick, asking Buildbucket for the build status
[14:06:48.747] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:48.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:162:0) after 1m0s
[14:07:48.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:162:0)
[14:07:48.806] Timer tick, asking Buildbucket for the build status
[14:07:49.656] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:49.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:163:0) after 1m0s
[14:08:49.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:163:0)
[14:08:49.707] Timer tick, asking Buildbucket for the build status
[14:08:49.770] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:49.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:164:0) after 1m0s
[14:09:49.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:164:0)
[14:09:49.792] Timer tick, asking Buildbucket for the build status
[14:09:49.937] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:165:0) after 1m0s
[14:10:49.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:165:0)
[14:10:49.978] Timer tick, asking Buildbucket for the build status
[14:10:50.249] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:50.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:166:0) after 1m0s
[14:11:50.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:166:0)
[14:11:50.969] Timer tick, asking Buildbucket for the build status
[14:11:51.056] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:51.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:167:0) after 1m0s
[14:12:51.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:167:0)
[14:12:51.120] Timer tick, asking Buildbucket for the build status
[14:12:51.283] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:51.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:168:0) after 1m0s
[14:13:51.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:168:0)
[14:13:51.474] Timer tick, asking Buildbucket for the build status
[14:13:51.543] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:51.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:169:0) after 1m0s
[14:14:51.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:169:0)
[14:14:51.674] Timer tick, asking Buildbucket for the build status
[14:14:51.748] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:51.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:170:0) after 1m0s
[14:15:51.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:170:0)
[14:15:51.954] Timer tick, asking Buildbucket for the build status
[14:15:52.163] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:52.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:171:0) after 1m0s
[14:16:52.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:171:0)
[14:16:52.238] Timer tick, asking Buildbucket for the build status
[14:16:52.381] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:52.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:172:0) after 1m0s
[14:17:52.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:172:0)
[14:17:52.569] Timer tick, asking Buildbucket for the build status
[14:17:52.750] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:52.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:173:0) after 1m0s
[14:18:52.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:173:0)
[14:18:52.810] Timer tick, asking Buildbucket for the build status
[14:18:52.869] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:52.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:174:0) after 1m0s
[14:19:53.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:174:0)
[14:19:53.024] Timer tick, asking Buildbucket for the build status
[14:19:53.115] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:53.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:175:0) after 1m0s
[14:20:53.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:175:0)
[14:20:53.201] Timer tick, asking Buildbucket for the build status
[14:20:53.281] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:53.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:176:0) after 1m0s
[14:21:53.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:176:0)
[14:21:53.350] Timer tick, asking Buildbucket for the build status
[14:21:53.554] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:53.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:177:0) after 1m0s
[14:22:53.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:177:0)
[14:22:53.615] Timer tick, asking Buildbucket for the build status
[14:22:54.108] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:54.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:178:0) after 1m0s
[14:23:54.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:178:0)
[14:23:54.284] Timer tick, asking Buildbucket for the build status
[14:23:54.405] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:54.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:179:0) after 1m0s
[14:24:54.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:179:0)
[14:24:54.440] Timer tick, asking Buildbucket for the build status
[14:24:54.526] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:54.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:180:0) after 1m0s
[14:25:54.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:180:0)
[14:25:54.674] Timer tick, asking Buildbucket for the build status
[14:25:54.751] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:54.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:181:0) after 1m0s
[14:26:54.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:181:0)
[14:26:54.891] Timer tick, asking Buildbucket for the build status
[14:26:54.954] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:54.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:182:0) after 1m0s
[14:27:55.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:182:0)
[14:27:55.035] Timer tick, asking Buildbucket for the build status
[14:27:55.163] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:55.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:183:0) after 1m0s
[14:28:55.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:183:0)
[14:28:55.211] Timer tick, asking Buildbucket for the build status
[14:28:55.440] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:55.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:184:0) after 1m0s
[14:29:55.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:184:0)
[14:29:55.469] Timer tick, asking Buildbucket for the build status
[14:29:55.530] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:55.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:185:0) after 1m0s
[14:30:55.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:185:0)
[14:30:55.668] Timer tick, asking Buildbucket for the build status
[14:30:56.135] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:56.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:186:0) after 1m0s
[14:31:56.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:186:0)
[14:31:56.298] Timer tick, asking Buildbucket for the build status
[14:31:56.406] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:56.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:187:0) after 1m0s
[14:32:56.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:187:0)
[14:32:56.445] Timer tick, asking Buildbucket for the build status
[14:32:56.606] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:56.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:188:0) after 1m0s
[14:33:56.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:188:0)
[14:33:56.657] Timer tick, asking Buildbucket for the build status
[14:33:56.769] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:56.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:189:0) after 1m0s
[14:34:56.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:189:0)
[14:34:56.848] Timer tick, asking Buildbucket for the build status
[14:34:57.129] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:57.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:190:0) after 1m0s
[14:35:57.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:190:0)
[14:35:57.340] Timer tick, asking Buildbucket for the build status
[14:35:57.677] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:57.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:191:0) after 1m0s
[14:36:57.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:191:0)
[14:36:57.740] Timer tick, asking Buildbucket for the build status
[14:36:57.945] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:192:0) after 1m0s
[14:37:58.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:192:0)
[14:37:58.086] Timer tick, asking Buildbucket for the build status
[14:37:58.426] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:58.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:193:0) after 1m0s
[14:38:58.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:193:0)
[14:38:58.472] Timer tick, asking Buildbucket for the build status
[14:38:58.571] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:58.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:194:0) after 1m0s
[14:39:58.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:194:0)
[14:39:58.604] Timer tick, asking Buildbucket for the build status
[14:39:58.734] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:195:0) after 1m0s
[14:40:58.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:195:0)
[14:40:58.741] Timer tick, asking Buildbucket for the build status
[14:40:58.905] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:58.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:196:0) after 1m0s
[14:41:58.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:196:0)
[14:41:58.912] Timer tick, asking Buildbucket for the build status
[14:41:59.018] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:197:0) after 1m0s
[14:42:59.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:197:0)
[14:42:59.045] Timer tick, asking Buildbucket for the build status
[14:42:59.437] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:59.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:198:0) after 1m0s
[14:43:59.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:198:0)
[14:43:59.493] Timer tick, asking Buildbucket for the build status
[14:43:59.583] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:59.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:199:0) after 1m0s
[14:44:59.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:199:0)
[14:44:59.655] Timer tick, asking Buildbucket for the build status
[14:44:59.955] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:59.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:200:0) after 1m0s
[14:46:00.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:200:0)
[14:46:00.031] Timer tick, asking Buildbucket for the build status
[14:46:00.222] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:00.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:201:0) after 1m0s
[14:47:00.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:201:0)
[14:47:00.368] Timer tick, asking Buildbucket for the build status
[14:47:00.533] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:00.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:202:0) after 1m0s
[14:48:01.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:202:0)
[14:48:01.173] Timer tick, asking Buildbucket for the build status
[14:48:01.836] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:203:0) after 1m0s
[14:49:01.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:203:0)
[14:49:01.909] Timer tick, asking Buildbucket for the build status
[14:49:02.113] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:02.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:204:0) after 1m0s
[14:50:02.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:204:0)
[14:50:02.157] Timer tick, asking Buildbucket for the build status
[14:50:02.333] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:205:0) after 1m0s
[14:51:02.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:205:0)
[14:51:02.420] Timer tick, asking Buildbucket for the build status
[14:51:02.670] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:206:0) after 1m0s
[14:52:02.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:206:0)
[14:52:02.699] Timer tick, asking Buildbucket for the build status
[14:52:02.892] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:207:0) after 1m0s
[14:53:02.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:207:0)
[14:53:02.947] Timer tick, asking Buildbucket for the build status
[14:53:03.084] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:03.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:208:0) after 1m0s
[14:54:03.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:208:0)
[14:54:03.094] Timer tick, asking Buildbucket for the build status
[14:54:03.257] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:03.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:209:0) after 1m0s
[14:55:03.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:209:0)
[14:55:03.367] Timer tick, asking Buildbucket for the build status
[14:55:03.537] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:03.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:210:0) after 1m0s
[14:56:03.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:210:0)
[14:56:03.610] Timer tick, asking Buildbucket for the build status
[14:56:04.543] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:04.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:211:0) after 1m0s
[14:57:04.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:211:0)
[14:57:04.573] Timer tick, asking Buildbucket for the build status
[14:57:04.695] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:04.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:212:0) after 1m0s
[14:58:04.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:212:0)
[14:58:04.773] Timer tick, asking Buildbucket for the build status
[14:58:04.883] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:04.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:213:0) after 1m0s
[14:59:04.953] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:213:0)
[14:59:04.953] Timer tick, asking Buildbucket for the build status
[14:59:05.250] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:05.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:214:0) after 1m0s
[15:00:05.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:214:0)
[15:00:05.304] Timer tick, asking Buildbucket for the build status
[15:00:05.752] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:05.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:215:0) after 1m0s
[15:01:05.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:215:0)
[15:01:05.991] Timer tick, asking Buildbucket for the build status
[15:01:06.062] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:06.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:216:0) after 1m0s
[15:02:06.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:216:0)
[15:02:06.124] Timer tick, asking Buildbucket for the build status
[15:02:06.995] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:06.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:217:0) after 1m0s
[15:03:07.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:217:0)
[15:03:07.236] Timer tick, asking Buildbucket for the build status
[15:03:07.486] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:07.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:218:0) after 1m0s
[15:04:07.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:218:0)
[15:04:07.559] Timer tick, asking Buildbucket for the build status
[15:04:07.631] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:07.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:219:0) after 1m0s
[15:05:07.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:219:0)
[15:05:07.703] Timer tick, asking Buildbucket for the build status
[15:05:07.873] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:07.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:220:0) after 1m0s
[15:06:07.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:220:0)
[15:06:07.938] Timer tick, asking Buildbucket for the build status
[15:06:08.043] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:08.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:221:0) after 1m0s
[15:07:08.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:221:0)
[15:07:08.057] Timer tick, asking Buildbucket for the build status
[15:07:08.333] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:08.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:222:0) after 1m0s
[15:08:08.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:222:0)
[15:08:08.389] Timer tick, asking Buildbucket for the build status
[15:08:08.614] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:08.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:223:0) after 1m0s
[15:09:08.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:223:0)
[15:09:08.685] Timer tick, asking Buildbucket for the build status
[15:09:09.141] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:09.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:224:0) after 1m0s
[15:10:09.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:224:0)
[15:10:09.319] Timer tick, asking Buildbucket for the build status
[15:10:09.389] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:09.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:225:0) after 1m0s
[15:11:09.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:225:0)
[15:11:09.510] Timer tick, asking Buildbucket for the build status
[15:11:09.581] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:09.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:226:0) after 1m0s
[15:12:09.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:226:0)
[15:12:09.613] Timer tick, asking Buildbucket for the build status
[15:12:09.716] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:09.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:227:0) after 1m0s
[15:13:09.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:227:0)
[15:13:09.750] Timer tick, asking Buildbucket for the build status
[15:13:09.810] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:09.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:228:0) after 1m0s
[15:14:09.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:228:0)
[15:14:09.824] Timer tick, asking Buildbucket for the build status
[15:14:09.995] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:09.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:229:0) after 1m0s
[15:15:10.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:229:0)
[15:15:10.121] Timer tick, asking Buildbucket for the build status
[15:15:10.255] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:10.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:230:0) after 1m0s
[15:16:10.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:230:0)
[15:16:10.352] Timer tick, asking Buildbucket for the build status
[15:16:10.455] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:10.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:231:0) after 1m0s
[15:17:10.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:231:0)
[15:17:10.741] Timer tick, asking Buildbucket for the build status
[15:17:10.853] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:10.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:232:0) after 1m0s
[15:18:10.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:232:0)
[15:18:10.899] Timer tick, asking Buildbucket for the build status
[15:18:11.518] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:11.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:233:0) after 1m0s
[15:19:11.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:233:0)
[15:19:11.596] Timer tick, asking Buildbucket for the build status
[15:19:11.661] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:11.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:234:0) after 1m0s
[15:20:11.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:234:0)
[15:20:11.696] Timer tick, asking Buildbucket for the build status
[15:20:11.754] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:11.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:235:0) after 1m0s
[15:21:11.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:235:0)
[15:21:11.918] Timer tick, asking Buildbucket for the build status
[15:21:12.002] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:12.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:236:0) after 1m0s
[15:22:12.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:236:0)
[15:22:12.169] Timer tick, asking Buildbucket for the build status
[15:22:12.309] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:12.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:237:0) after 1m0s
[15:23:12.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:237:0)
[15:23:12.374] Timer tick, asking Buildbucket for the build status
[15:23:12.733] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:12.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:238:0) after 1m0s
[15:24:12.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:238:0)
[15:24:12.758] Timer tick, asking Buildbucket for the build status
[15:24:12.885] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:12.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:239:0) after 1m0s
[15:25:12.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:239:0)
[15:25:12.951] Timer tick, asking Buildbucket for the build status
[15:25:13.022] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:13.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:240:0) after 1m0s
[15:26:13.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:240:0)
[15:26:13.063] Timer tick, asking Buildbucket for the build status
[15:26:13.231] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:13.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:241:0) after 1m0s
[15:27:13.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:241:0)
[15:27:13.261] Timer tick, asking Buildbucket for the build status
[15:27:13.382] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:13.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:242:0) after 1m0s
[15:28:13.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:242:0)
[15:28:13.434] Timer tick, asking Buildbucket for the build status
[15:28:13.763] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:13.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:243:0) after 1m0s
[15:29:13.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:243:0)
[15:29:13.806] Timer tick, asking Buildbucket for the build status
[15:29:13.970] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:13.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:244:0) after 1m0s
[15:30:14.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:244:0)
[15:30:14.019] Timer tick, asking Buildbucket for the build status
[15:30:14.172] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:14.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:245:0) after 1m0s
[15:31:14.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:245:0)
[15:31:14.247] Timer tick, asking Buildbucket for the build status
[15:31:14.331] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:14.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:246:0) after 1m0s
[15:32:14.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:246:0)
[15:32:14.412] Timer tick, asking Buildbucket for the build status
[15:32:14.709] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:14.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:247:0) after 1m0s
[15:33:14.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:247:0)
[15:33:14.749] Timer tick, asking Buildbucket for the build status
[15:33:14.809] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:14.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:248:0) after 1m0s
[15:34:14.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:248:0)
[15:34:14.959] Timer tick, asking Buildbucket for the build status
[15:34:15.035] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:15.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:249:0) after 1m0s
[15:35:15.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:249:0)
[15:35:15.227] Timer tick, asking Buildbucket for the build status
[15:35:15.320] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:15.320] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:250:0) after 1m0s
[15:36:15.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:250:0)
[15:36:15.435] Timer tick, asking Buildbucket for the build status
[15:36:15.586] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:15.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:251:0) after 1m0s
[15:37:15.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:251:0)
[15:37:15.810] Timer tick, asking Buildbucket for the build status
[15:37:15.943] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:15.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:252:0) after 1m0s
[15:38:16.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:252:0)
[15:38:16.535] Timer tick, asking Buildbucket for the build status
[15:38:16.634] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:16.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:253:0) after 1m0s
[15:39:16.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:253:0)
[15:39:16.877] Timer tick, asking Buildbucket for the build status
[15:39:16.951] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:16.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:254:0) after 1m0s
[15:40:16.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:254:0)
[15:40:16.984] Timer tick, asking Buildbucket for the build status
[15:40:17.045] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:17.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:255:0) after 1m0s
[15:41:17.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:255:0)
[15:41:17.106] Timer tick, asking Buildbucket for the build status
[15:41:17.168] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:17.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:256:0) after 1m0s
[15:42:17.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:256:0)
[15:42:17.871] Timer tick, asking Buildbucket for the build status
[15:42:18.245] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:18.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:257:0) after 1m0s
[15:43:18.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:257:0)
[15:43:18.963] Timer tick, asking Buildbucket for the build status
[15:43:19.024] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:19.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:258:0) after 1m0s
[15:44:19.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:258:0)
[15:44:19.303] Timer tick, asking Buildbucket for the build status
[15:44:19.373] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:19.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:259:0) after 1m0s
[15:45:19.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:259:0)
[15:45:19.492] Timer tick, asking Buildbucket for the build status
[15:45:19.651] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:19.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:260:0) after 1m0s
[15:46:19.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:260:0)
[15:46:19.892] Timer tick, asking Buildbucket for the build status
[15:46:20.023] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:20.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:261:0) after 1m0s
[15:47:20.667] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:261:0)
[15:47:20.667] Timer tick, asking Buildbucket for the build status
[15:47:20.998] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:20.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:262:0) after 1m0s
[15:48:21.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:262:0)
[15:48:21.392] Timer tick, asking Buildbucket for the build status
[15:48:21.513] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:21.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:263:0) after 1m0s
[15:49:21.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:263:0)
[15:49:21.613] Timer tick, asking Buildbucket for the build status
[15:49:21.745] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:21.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:264:0) after 1m0s
[15:50:21.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:264:0)
[15:50:21.828] Timer tick, asking Buildbucket for the build status
[15:50:22.344] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:22.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:265:0) after 1m0s
[15:51:23.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:265:0)
[15:51:23.254] Timer tick, asking Buildbucket for the build status
[15:51:23.457] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:23.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:266:0) after 1m0s
[15:52:23.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:266:0)
[15:52:23.517] Timer tick, asking Buildbucket for the build status
[15:52:23.627] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:23.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:267:0) after 1m0s
[15:53:23.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:267:0)
[15:53:23.731] Timer tick, asking Buildbucket for the build status
[15:53:23.793] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:23.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:268:0) after 1m0s
[15:54:23.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:268:0)
[15:54:23.835] Timer tick, asking Buildbucket for the build status
[15:54:23.913] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:23.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:269:0) after 1m0s
[15:55:24.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:269:0)
[15:55:24.104] Timer tick, asking Buildbucket for the build status
[15:55:24.286] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:24.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:270:0) after 1m0s
[15:56:24.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:270:0)
[15:56:24.286] Timer tick, asking Buildbucket for the build status
[15:56:24.385] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:24.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:271:0) after 1m0s
[15:57:24.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:271:0)
[15:57:24.417] Timer tick, asking Buildbucket for the build status
[15:57:24.569] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:24.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:272:0) after 1m0s
[15:58:24.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:272:0)
[15:58:24.634] Timer tick, asking Buildbucket for the build status
[15:58:24.765] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:24.765] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:273:0) after 1m0s
[15:59:24.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:273:0)
[15:59:24.853] Timer tick, asking Buildbucket for the build status
[15:59:25.188] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:25.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:274:0) after 1m0s
[16:00:25.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:274:0)
[16:00:25.220] Timer tick, asking Buildbucket for the build status
[16:00:25.445] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:25.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:275:0) after 1m0s
[16:01:25.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:275:0)
[16:01:25.535] Timer tick, asking Buildbucket for the build status
[16:01:25.602] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:25.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:276:0) after 1m0s
[16:02:25.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:276:0)
[16:02:25.684] Timer tick, asking Buildbucket for the build status
[16:02:26.369] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:26.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:277:0) after 1m0s
[16:03:26.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:277:0)
[16:03:26.579] Timer tick, asking Buildbucket for the build status
[16:03:27.067] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:27.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:278:0) after 1m0s
[16:04:27.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:278:0)
[16:04:27.121] Timer tick, asking Buildbucket for the build status
[16:04:27.342] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:27.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:279:0) after 1m0s
[16:05:27.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:279:0)
[16:05:27.403] Timer tick, asking Buildbucket for the build status
[16:05:27.537] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:27.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:280:0) after 1m0s
[16:06:27.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:280:0)
[16:06:27.569] Timer tick, asking Buildbucket for the build status
[16:06:27.799] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:27.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:281:0) after 1m0s
[16:07:27.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:281:0)
[16:07:27.815] Timer tick, asking Buildbucket for the build status
[16:07:28.194] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:28.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:282:0) after 1m0s
[16:08:28.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:282:0)
[16:08:28.284] Timer tick, asking Buildbucket for the build status
[16:08:28.723] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:28.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:283:0) after 1m0s
[16:09:28.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:283:0)
[16:09:28.845] Timer tick, asking Buildbucket for the build status
[16:09:28.932] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:28.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:284:0) after 1m0s
[16:10:29.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:284:0)
[16:10:29.206] Timer tick, asking Buildbucket for the build status
[16:10:29.442] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:29.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:285:0) after 1m0s
[16:11:29.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:285:0)
[16:11:29.668] Timer tick, asking Buildbucket for the build status
[16:11:29.885] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:29.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:286:0) after 1m0s
[16:12:30.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:286:0)
[16:12:30.012] Timer tick, asking Buildbucket for the build status
[16:12:30.654] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:30.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:287:0) after 1m0s
[16:13:30.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:287:0)
[16:13:30.686] Timer tick, asking Buildbucket for the build status
[16:13:30.767] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:30.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:288:0) after 1m0s
[16:14:30.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:288:0)
[16:14:30.844] Timer tick, asking Buildbucket for the build status
[16:14:31.180] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:31.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:289:0) after 1m0s
[16:15:31.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:289:0)
[16:15:31.215] Timer tick, asking Buildbucket for the build status
[16:15:31.346] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:31.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:290:0) after 1m0s
[16:16:31.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:290:0)
[16:16:31.380] Timer tick, asking Buildbucket for the build status
[16:16:31.448] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:31.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:291:0) after 1m0s
[16:17:31.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:291:0)
[16:17:31.467] Timer tick, asking Buildbucket for the build status
[16:17:31.592] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:31.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:292:0) after 1m0s
[16:18:31.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:292:0)
[16:18:31.708] Timer tick, asking Buildbucket for the build status
[16:18:31.818] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:31.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:293:0) after 1m0s
[16:19:31.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:293:0)
[16:19:31.849] Timer tick, asking Buildbucket for the build status
[16:19:31.915] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:31.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:294:0) after 1m0s
[16:20:31.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:294:0)
[16:20:31.982] Timer tick, asking Buildbucket for the build status
[16:20:32.153] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:32.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:295:0) after 1m0s
[16:21:32.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:295:0)
[16:21:32.362] Timer tick, asking Buildbucket for the build status
[16:21:32.449] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:32.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:296:0) after 1m0s
[16:22:32.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:296:0)
[16:22:32.496] Timer tick, asking Buildbucket for the build status
[16:22:32.608] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:32.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:297:0) after 1m0s
[16:23:32.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:297:0)
[16:23:32.800] Timer tick, asking Buildbucket for the build status
[16:23:32.915] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:32.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:298:0) after 1m0s
[16:24:32.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:298:0)
[16:24:32.973] Timer tick, asking Buildbucket for the build status
[16:24:33.052] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:33.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:299:0) after 1m0s
[16:25:33.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:299:0)
[16:25:33.101] Timer tick, asking Buildbucket for the build status
[16:25:33.172] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:33.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:300:0) after 1m0s
[16:26:33.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:300:0)
[16:26:33.269] Timer tick, asking Buildbucket for the build status
[16:26:33.329] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:33.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:301:0) after 1m0s
[16:27:33.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:301:0)
[16:27:33.416] Timer tick, asking Buildbucket for the build status
[16:27:33.576] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:33.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:302:0) after 1m0s
[16:28:33.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:302:0)
[16:28:33.623] Timer tick, asking Buildbucket for the build status
[16:28:33.733] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:33.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:303:0) after 1m0s
[16:29:33.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:303:0)
[16:29:33.763] Timer tick, asking Buildbucket for the build status
[16:29:33.855] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:33.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:304:0) after 1m0s
[16:30:33.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:304:0)
[16:30:33.904] Timer tick, asking Buildbucket for the build status
[16:30:34.015] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:34.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:305:0) after 1m0s
[16:31:34.100] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:305:0)
[16:31:34.100] Timer tick, asking Buildbucket for the build status
[16:31:34.277] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:34.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:306:0) after 1m0s
[16:32:34.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:306:0)
[16:32:34.386] Timer tick, asking Buildbucket for the build status
[16:32:34.490] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:34.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:307:0) after 1m0s
[16:33:34.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:307:0)
[16:33:34.523] Timer tick, asking Buildbucket for the build status
[16:33:34.595] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:34.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:308:0) after 1m0s
[16:34:34.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:308:0)
[16:34:34.650] Timer tick, asking Buildbucket for the build status
[16:34:34.716] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:34.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:309:0) after 1m0s
[16:35:34.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:309:0)
[16:35:34.800] Timer tick, asking Buildbucket for the build status
[16:35:34.880] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:310:0) after 1m0s
[16:36:34.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:310:0)
[16:36:34.940] Timer tick, asking Buildbucket for the build status
[16:36:35.056] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:35.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:311:0) after 1m0s
[16:37:35.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:311:0)
[16:37:35.184] Timer tick, asking Buildbucket for the build status
[16:37:35.357] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:35.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:312:0) after 1m0s
[16:38:35.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:312:0)
[16:38:35.462] Timer tick, asking Buildbucket for the build status
[16:38:35.570] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:35.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:313:0) after 1m0s
[16:39:35.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:313:0)
[16:39:35.640] Timer tick, asking Buildbucket for the build status
[16:39:35.785] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:35.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:314:0) after 1m0s
[16:40:35.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:314:0)
[16:40:35.914] Timer tick, asking Buildbucket for the build status
[16:40:36.078] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:36.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:315:0) after 1m0s
[16:41:36.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:315:0)
[16:41:36.151] Timer tick, asking Buildbucket for the build status
[16:41:36.261] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:36.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:316:0) after 1m0s
[16:42:36.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:316:0)
[16:42:36.337] Timer tick, asking Buildbucket for the build status
[16:42:36.457] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:36.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:317:0) after 1m0s
[16:43:36.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:317:0)
[16:43:36.521] Timer tick, asking Buildbucket for the build status
[16:43:36.818] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:36.818] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:318:0) after 1m0s
[16:44:37.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:318:0)
[16:44:37.062] Timer tick, asking Buildbucket for the build status
[16:44:37.240] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:37.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:319:0) after 1m0s
[16:45:37.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:319:0)
[16:45:37.367] Timer tick, asking Buildbucket for the build status
[16:45:37.445] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:37.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:320:0) after 1m0s
[16:46:37.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:320:0)
[16:46:37.487] Timer tick, asking Buildbucket for the build status
[16:46:37.628] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:37.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:321:0) after 1m0s
[16:47:37.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:321:0)
[16:47:37.728] Timer tick, asking Buildbucket for the build status
[16:47:37.908] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:37.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:322:0) after 1m0s
[16:48:38.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:322:0)
[16:48:38.088] Timer tick, asking Buildbucket for the build status
[16:48:38.185] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:38.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:323:0) after 1m0s
[16:49:38.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:323:0)
[16:49:38.294] Timer tick, asking Buildbucket for the build status
[16:49:38.428] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:38.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:324:0) after 1m0s
[16:50:38.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:324:0)
[16:50:38.474] Timer tick, asking Buildbucket for the build status
[16:50:38.543] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:38.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:325:0) after 1m0s
[16:51:38.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:325:0)
[16:51:38.574] Timer tick, asking Buildbucket for the build status
[16:51:38.769] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:38.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:326:0) after 1m0s
[16:52:38.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:326:0)
[16:52:38.839] Timer tick, asking Buildbucket for the build status
[16:52:38.985] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:38.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:327:0) after 1m0s
[16:53:39.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:327:0)
[16:53:39.042] Timer tick, asking Buildbucket for the build status
[16:53:39.097] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:328:0) after 1m0s
[16:54:39.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:328:0)
[16:54:39.113] Timer tick, asking Buildbucket for the build status
[16:54:39.174] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:39.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:329:0) after 1m0s
[16:55:39.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:329:0)
[16:55:39.321] Timer tick, asking Buildbucket for the build status
[16:55:39.393] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:39.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:330:0) after 1m0s
[16:56:39.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:330:0)
[16:56:39.483] Timer tick, asking Buildbucket for the build status
[16:56:39.563] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:39.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:331:0) after 1m0s
[16:57:39.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:331:0)
[16:57:39.610] Timer tick, asking Buildbucket for the build status
[16:57:39.737] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:39.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:332:0) after 1m0s
[16:58:40.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:332:0)
[16:58:40.023] Timer tick, asking Buildbucket for the build status
[16:58:40.104] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:40.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:333:0) after 1m0s
[16:59:40.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:333:0)
[16:59:40.202] Timer tick, asking Buildbucket for the build status
[16:59:40.398] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:40.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:334:0) after 1m0s
[17:00:40.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:334:0)
[17:00:40.460] Timer tick, asking Buildbucket for the build status
[17:00:40.540] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:40.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:335:0) after 1m0s
[17:01:40.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:335:0)
[17:01:40.572] Timer tick, asking Buildbucket for the build status
[17:01:40.637] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:40.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:336:0) after 1m0s
[17:02:40.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:336:0)
[17:02:40.826] Timer tick, asking Buildbucket for the build status
[17:02:40.967] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:40.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:337:0) after 1m0s
[17:03:41.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:337:0)
[17:03:41.157] Timer tick, asking Buildbucket for the build status
[17:03:41.275] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:41.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:338:0) after 1m0s
[17:04:41.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:338:0)
[17:04:41.472] Timer tick, asking Buildbucket for the build status
[17:04:41.551] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:339:0) after 1m0s
[17:05:41.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:339:0)
[17:05:41.581] Timer tick, asking Buildbucket for the build status
[17:05:42.594] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:42.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:340:0) after 1m0s
[17:06:42.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:340:0)
[17:06:42.684] Timer tick, asking Buildbucket for the build status
[17:06:43.324] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:43.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:341:0) after 1m0s
[17:07:43.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:341:0)
[17:07:43.381] Timer tick, asking Buildbucket for the build status
[17:07:43.506] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:43.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:342:0) after 1m0s
[17:08:43.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:342:0)
[17:08:43.543] Timer tick, asking Buildbucket for the build status
[17:08:43.694] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:43.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:343:0) after 1m0s
[17:09:43.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:343:0)
[17:09:43.727] Timer tick, asking Buildbucket for the build status
[17:09:44.012] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:44.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:344:0) after 1m0s
[17:10:44.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:344:0)
[17:10:44.011] Timer tick, asking Buildbucket for the build status
[17:10:44.238] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:44.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:345:0) after 1m0s
[17:11:44.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:345:0)
[17:11:44.255] Timer tick, asking Buildbucket for the build status
[17:11:44.599] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:44.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:346:0) after 1m0s
[17:12:44.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:346:0)
[17:12:44.631] Timer tick, asking Buildbucket for the build status
[17:12:44.744] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:347:0) after 1m0s
[17:13:44.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:347:0)
[17:13:44.831] Timer tick, asking Buildbucket for the build status
[17:13:44.900] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:44.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:348:0) after 1m0s
[17:14:45.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:348:0)
[17:14:45.016] Timer tick, asking Buildbucket for the build status
[17:14:45.244] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:45.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:349:0) after 1m0s
[17:15:45.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:349:0)
[17:15:45.377] Timer tick, asking Buildbucket for the build status
[17:15:45.611] Build 8856501708502601168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021931770624335120:350:0) after 1m0s
[17:16:29.561] Received PubSub notification, asking Buildbucket for the build status
[17:16:29.657] Build 8856501708502601168: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:16:29.657] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1612199788023079",
    "created_by": "project:nacl",
    "created_ts": "1612178809600908",
    "failure_reason": "BUILD_FAILURE",
    "id": "8856501708502601168",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-jd8d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7085, \"got_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-jd8d\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1612187105170609",
    "status": "COMPLETED",
    "status_changed_ts": "1612187105907989",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7085",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021931770624335120",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856501708502601168/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:517629eee8101f10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1612199788157406",
    "url": "https://ci.chromium.org/b/8856501708502601168",
    "utcnow_ts": "1612199789634624"
  }
}
[17:16:29.657] Invocation finished in 5h49m41.463433388s with status FAILED