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

• 6f1149defa9e40c9bd20512b5560959d381ac202 (6 years ago)

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

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

[21:29:48.266] New invocation is queued and will start shortly
[21:29:49.585] Starting the invocation (attempt 1)
[21:29:49.619] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:29:49.624] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:29:49.624] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9100531999918678512",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"6f1149defa9e40c9bd20512b5560959d381ac202\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9100531999918678512",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/6f1149defa9e40c9bd20512b5560959d381ac202",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/6f1149defa9e40c9bd20512b5560959d381ac202",
    "gitiles_ref:refs/heads/master"
  ]
}
[21:29:51.227] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1537219789782822",
    "experimental": true,
    "id": "8935101937667334816",
    "lease_expiration_ts": "1537338590987983",
    "lease_key": "2052913861",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"6f1149defa9e40c9bd20512b5560959d381ac202\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1537219791145607",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/6f1149defa9e40c9bd20512b5560959d381ac202",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/6f1149defa9e40c9bd20512b5560959d381ac202",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9100531999918678512",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
      "swarming_tag:buildbucket_bucket:luci.nacl.toolchain",
      "swarming_tag:buildbucket_build_id:8935101937667334816",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/6f1149defa9e40c9bd20512b5560959d381ac202",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/6f1149defa9e40c9bd20512b5560959d381ac202",
      "swarming_tag:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8935101937667334816/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:60",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9100531999918678512",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:aab2657fb2352342b61c0380ef1d346e0cfd7da5",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4002420f3c617510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1537219791145715",
    "url": "https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
    "utcnow_ts": "1537219791216366"
  }
}
[21:29:51.227] Task URL: https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4
[21:29:51.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:2:0) after 1m0s
[21:30:38.729] Received PubSub notification, asking Buildbucket for the build status
[21:30:38.777] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:51.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:2:0)
[21:30:51.243] Timer tick, asking Buildbucket for the build status
[21:30:51.373] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:51.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:4:0) after 1m0s
[21:31:51.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:4:0)
[21:31:51.401] Timer tick, asking Buildbucket for the build status
[21:31:51.563] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:51.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:5:0) after 1m0s
[21:32:51.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:5:0)
[21:32:51.585] Timer tick, asking Buildbucket for the build status
[21:32:51.650] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:51.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:6:0) after 1m0s
[21:33:51.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:6:0)
[21:33:51.683] Timer tick, asking Buildbucket for the build status
[21:33:51.757] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:51.757] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:7:0) after 1m0s
[21:34:51.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:7:0)
[21:34:51.770] Timer tick, asking Buildbucket for the build status
[21:34:51.850] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:51.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:8:0) after 1m0s
[21:35:51.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:8:0)
[21:35:51.882] Timer tick, asking Buildbucket for the build status
[21:35:52.060] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:52.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:9:0) after 1m0s
[21:36:52.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:9:0)
[21:36:52.092] Timer tick, asking Buildbucket for the build status
[21:36:52.176] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:52.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:10:0) after 1m0s
[21:37:52.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:10:0)
[21:37:52.208] Timer tick, asking Buildbucket for the build status
[21:37:52.333] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:52.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:11:0) after 1m0s
[21:38:52.348] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:11:0)
[21:38:52.348] Timer tick, asking Buildbucket for the build status
[21:38:52.420] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:52.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:12:0) after 1m0s
[21:39:52.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:12:0)
[21:39:52.469] Timer tick, asking Buildbucket for the build status
[21:39:52.552] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:52.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:13:0) after 1m0s
[21:40:52.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:13:0)
[21:40:52.575] Timer tick, asking Buildbucket for the build status
[21:40:52.651] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:52.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:14:0) after 1m0s
[21:41:52.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:14:0)
[21:41:52.675] Timer tick, asking Buildbucket for the build status
[21:41:52.805] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:52.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:15:0) after 1m0s
[21:42:52.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:15:0)
[21:42:52.842] Timer tick, asking Buildbucket for the build status
[21:42:53.013] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:53.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:16:0) after 1m0s
[21:43:53.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:16:0)
[21:43:53.035] Timer tick, asking Buildbucket for the build status
[21:43:53.424] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:53.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:17:0) after 1m0s
[21:44:53.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:17:0)
[21:44:53.440] Timer tick, asking Buildbucket for the build status
[21:44:53.567] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:53.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:18:0) after 1m0s
[21:45:53.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:18:0)
[21:45:53.584] Timer tick, asking Buildbucket for the build status
[21:45:53.691] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:53.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:19:0) after 1m0s
[21:46:53.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:19:0)
[21:46:53.716] Timer tick, asking Buildbucket for the build status
[21:46:53.814] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:53.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:20:0) after 1m0s
[21:47:53.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:20:0)
[21:47:53.833] Timer tick, asking Buildbucket for the build status
[21:47:53.946] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:53.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:21:0) after 1m0s
[21:48:53.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:21:0)
[21:48:53.963] Timer tick, asking Buildbucket for the build status
[21:48:54.032] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:22:0) after 1m0s
[21:49:54.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:22:0)
[21:49:54.054] Timer tick, asking Buildbucket for the build status
[21:49:54.149] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:54.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:23:0) after 1m0s
[21:50:54.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:23:0)
[21:50:54.252] Timer tick, asking Buildbucket for the build status
[21:50:54.335] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:54.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:24:0) after 1m0s
[21:51:54.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:24:0)
[21:51:54.350] Timer tick, asking Buildbucket for the build status
[21:51:54.513] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:54.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:25:0) after 1m0s
[21:52:54.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:25:0)
[21:52:54.534] Timer tick, asking Buildbucket for the build status
[21:52:54.717] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:54.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:26:0) after 1m0s
[21:53:54.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:26:0)
[21:53:54.742] Timer tick, asking Buildbucket for the build status
[21:53:54.939] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:54.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:27:0) after 1m0s
[21:54:54.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:27:0)
[21:54:54.979] Timer tick, asking Buildbucket for the build status
[21:54:55.133] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:55.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:28:0) after 1m0s
[21:55:55.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:28:0)
[21:55:55.172] Timer tick, asking Buildbucket for the build status
[21:55:55.229] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:55.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:29:0) after 1m0s
[21:56:55.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:29:0)
[21:56:55.248] Timer tick, asking Buildbucket for the build status
[21:56:55.310] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:55.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:30:0) after 1m0s
[21:57:55.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:30:0)
[21:57:55.328] Timer tick, asking Buildbucket for the build status
[21:57:55.372] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:55.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:31:0) after 1m0s
[21:58:55.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:31:0)
[21:58:55.423] Timer tick, asking Buildbucket for the build status
[21:58:55.660] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:55.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:32:0) after 1m0s
[21:59:55.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:32:0)
[21:59:55.677] Timer tick, asking Buildbucket for the build status
[21:59:55.745] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:55.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:33:0) after 1m0s
[22:00:55.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:33:0)
[22:00:55.774] Timer tick, asking Buildbucket for the build status
[22:00:55.837] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:55.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:34:0) after 1m0s
[22:01:55.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:34:0)
[22:01:55.882] Timer tick, asking Buildbucket for the build status
[22:01:55.931] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:55.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:35:0) after 1m0s
[22:02:55.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:35:0)
[22:02:55.949] Timer tick, asking Buildbucket for the build status
[22:02:56.073] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:56.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:36:0) after 1m0s
[22:03:56.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:36:0)
[22:03:56.099] Timer tick, asking Buildbucket for the build status
[22:03:56.172] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:56.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:37:0) after 1m0s
[22:04:56.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:37:0)
[22:04:56.286] Timer tick, asking Buildbucket for the build status
[22:04:56.337] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:56.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:38:0) after 1m0s
[22:05:56.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:38:0)
[22:05:56.361] Timer tick, asking Buildbucket for the build status
[22:05:56.461] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:56.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:39:0) after 1m0s
[22:06:56.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:39:0)
[22:06:56.482] Timer tick, asking Buildbucket for the build status
[22:06:56.568] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:56.568] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:40:0) after 1m0s
[22:07:56.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:40:0)
[22:07:56.584] Timer tick, asking Buildbucket for the build status
[22:07:56.627] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:56.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:41:0) after 1m0s
[22:08:56.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:41:0)
[22:08:56.643] Timer tick, asking Buildbucket for the build status
[22:08:56.702] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:56.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:42:0) after 1m0s
[22:09:56.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:42:0)
[22:09:56.794] Timer tick, asking Buildbucket for the build status
[22:09:56.909] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:56.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:43:0) after 1m0s
[22:10:56.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:43:0)
[22:10:56.943] Timer tick, asking Buildbucket for the build status
[22:10:57.057] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:57.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:44:0) after 1m0s
[22:11:57.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:44:0)
[22:11:57.073] Timer tick, asking Buildbucket for the build status
[22:11:57.140] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:57.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:45:0) after 1m0s
[22:12:57.209] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:45:0)
[22:12:57.209] Timer tick, asking Buildbucket for the build status
[22:12:57.291] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:57.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:46:0) after 1m0s
[22:13:57.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:46:0)
[22:13:57.312] Timer tick, asking Buildbucket for the build status
[22:13:57.385] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:57.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:47:0) after 1m0s
[22:14:57.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:47:0)
[22:14:57.404] Timer tick, asking Buildbucket for the build status
[22:14:57.475] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:57.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:48:0) after 1m0s
[22:15:57.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:48:0)
[22:15:57.502] Timer tick, asking Buildbucket for the build status
[22:15:57.609] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:57.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:49:0) after 1m0s
[22:16:57.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:49:0)
[22:16:57.643] Timer tick, asking Buildbucket for the build status
[22:16:57.733] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:57.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:50:0) after 1m0s
[22:17:57.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:50:0)
[22:17:57.758] Timer tick, asking Buildbucket for the build status
[22:17:57.981] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:57.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:51:0) after 1m0s
[22:18:58.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:51:0)
[22:18:58.011] Timer tick, asking Buildbucket for the build status
[22:18:58.086] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:58.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:52:0) after 1m0s
[22:19:58.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:52:0)
[22:19:58.162] Timer tick, asking Buildbucket for the build status
[22:19:58.464] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:58.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:53:0) after 1m0s
[22:20:58.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:53:0)
[22:20:58.492] Timer tick, asking Buildbucket for the build status
[22:20:58.543] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:58.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:54:0) after 1m0s
[22:21:58.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:54:0)
[22:21:58.565] Timer tick, asking Buildbucket for the build status
[22:21:58.776] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:58.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:55:0) after 1m0s
[22:22:58.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:55:0)
[22:22:58.793] Timer tick, asking Buildbucket for the build status
[22:22:58.929] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:58.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:56:0) after 1m0s
[22:23:58.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:56:0)
[22:23:58.945] Timer tick, asking Buildbucket for the build status
[22:23:59.011] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:59.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:57:0) after 1m0s
[22:24:59.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:57:0)
[22:24:59.114] Timer tick, asking Buildbucket for the build status
[22:24:59.346] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:59.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:58:0) after 1m0s
[22:25:59.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:58:0)
[22:25:59.383] Timer tick, asking Buildbucket for the build status
[22:25:59.445] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:59.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:59:0) after 1m0s
[22:26:59.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:59:0)
[22:26:59.468] Timer tick, asking Buildbucket for the build status
[22:26:59.540] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:59.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:60:0) after 1m0s
[22:27:59.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:60:0)
[22:27:59.577] Timer tick, asking Buildbucket for the build status
[22:27:59.992] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:59.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:61:0) after 1m0s
[22:29:00.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:61:0)
[22:29:00.072] Timer tick, asking Buildbucket for the build status
[22:29:00.158] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:00.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:62:0) after 1m0s
[22:30:00.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:62:0)
[22:30:00.178] Timer tick, asking Buildbucket for the build status
[22:30:00.240] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:00.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:63:0) after 1m0s
[22:31:00.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:63:0)
[22:31:00.288] Timer tick, asking Buildbucket for the build status
[22:31:00.331] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:00.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:64:0) after 1m0s
[22:32:00.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:64:0)
[22:32:00.352] Timer tick, asking Buildbucket for the build status
[22:32:00.457] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:00.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:65:0) after 1m0s
[22:33:00.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:65:0)
[22:33:00.475] Timer tick, asking Buildbucket for the build status
[22:33:00.713] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:00.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:66:0) after 1m0s
[22:34:00.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:66:0)
[22:34:00.738] Timer tick, asking Buildbucket for the build status
[22:34:00.954] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:00.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:67:0) after 1m0s
[22:35:01.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:67:0)
[22:35:01.041] Timer tick, asking Buildbucket for the build status
[22:35:01.141] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:01.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:68:0) after 1m0s
[22:36:01.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:68:0)
[22:36:01.160] Timer tick, asking Buildbucket for the build status
[22:36:01.211] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:01.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:69:0) after 1m0s
[22:37:01.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:69:0)
[22:37:01.256] Timer tick, asking Buildbucket for the build status
[22:37:01.368] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:01.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:70:0) after 1m0s
[22:38:01.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:70:0)
[22:38:01.391] Timer tick, asking Buildbucket for the build status
[22:38:01.475] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:01.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:71:0) after 1m0s
[22:39:01.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:71:0)
[22:39:01.503] Timer tick, asking Buildbucket for the build status
[22:39:01.675] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:72:0) after 1m0s
[22:40:01.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:72:0)
[22:40:01.698] Timer tick, asking Buildbucket for the build status
[22:40:01.756] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:01.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:73:0) after 1m0s
[22:41:01.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:73:0)
[22:41:01.776] Timer tick, asking Buildbucket for the build status
[22:41:01.831] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:01.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:74:0) after 1m0s
[22:42:01.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:74:0)
[22:42:01.858] Timer tick, asking Buildbucket for the build status
[22:42:03.095] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:03.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:75:0) after 1m0s
[22:43:03.122] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:75:0)
[22:43:03.123] Timer tick, asking Buildbucket for the build status
[22:43:03.321] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:03.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:76:0) after 1m0s
[22:44:03.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:76:0)
[22:44:03.342] Timer tick, asking Buildbucket for the build status
[22:44:03.403] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:03.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:77:0) after 1m0s
[22:45:03.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:77:0)
[22:45:03.431] Timer tick, asking Buildbucket for the build status
[22:45:03.485] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:03.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:78:0) after 1m0s
[22:46:03.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:78:0)
[22:46:03.514] Timer tick, asking Buildbucket for the build status
[22:46:03.745] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:03.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:79:0) after 1m0s
[22:47:03.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:79:0)
[22:47:03.776] Timer tick, asking Buildbucket for the build status
[22:47:03.884] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:03.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:80:0) after 1m0s
[22:48:03.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:80:0)
[22:48:03.903] Timer tick, asking Buildbucket for the build status
[22:48:03.957] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:03.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:81:0) after 1m0s
[22:49:03.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:81:0)
[22:49:03.977] Timer tick, asking Buildbucket for the build status
[22:49:04.038] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:04.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:82:0) after 1m0s
[22:50:04.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:82:0)
[22:50:04.063] Timer tick, asking Buildbucket for the build status
[22:50:04.273] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:04.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:83:0) after 1m0s
[22:51:04.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:83:0)
[22:51:04.292] Timer tick, asking Buildbucket for the build status
[22:51:04.344] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:04.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:84:0) after 1m0s
[22:52:04.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:84:0)
[22:52:04.379] Timer tick, asking Buildbucket for the build status
[22:52:04.486] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:04.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:85:0) after 1m0s
[22:53:04.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:85:0)
[22:53:04.605] Timer tick, asking Buildbucket for the build status
[22:53:04.912] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:04.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:86:0) after 1m0s
[22:54:04.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:86:0)
[22:54:04.933] Timer tick, asking Buildbucket for the build status
[22:54:05.030] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:05.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:87:0) after 1m0s
[22:55:05.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:87:0)
[22:55:05.052] Timer tick, asking Buildbucket for the build status
[22:55:05.184] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:05.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:88:0) after 1m0s
[22:56:05.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:88:0)
[22:56:05.207] Timer tick, asking Buildbucket for the build status
[22:56:05.276] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:05.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:89:0) after 1m0s
[22:57:05.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:89:0)
[22:57:05.297] Timer tick, asking Buildbucket for the build status
[22:57:05.353] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:05.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:90:0) after 1m0s
[22:58:05.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:90:0)
[22:58:05.384] Timer tick, asking Buildbucket for the build status
[22:58:05.542] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:05.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:91:0) after 1m0s
[22:59:05.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:91:0)
[22:59:05.612] Timer tick, asking Buildbucket for the build status
[22:59:05.672] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:05.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:92:0) after 1m0s
[23:00:05.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:92:0)
[23:00:05.692] Timer tick, asking Buildbucket for the build status
[23:00:05.914] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:05.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:93:0) after 1m0s
[23:01:06.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:93:0)
[23:01:06.004] Timer tick, asking Buildbucket for the build status
[23:01:06.143] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:06.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:94:0) after 1m0s
[23:02:06.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:94:0)
[23:02:06.165] Timer tick, asking Buildbucket for the build status
[23:02:06.699] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:06.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:95:0) after 1m0s
[23:03:06.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:95:0)
[23:03:06.718] Timer tick, asking Buildbucket for the build status
[23:03:06.791] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:06.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:96:0) after 1m0s
[23:04:06.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:96:0)
[23:04:06.810] Timer tick, asking Buildbucket for the build status
[23:04:06.903] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:06.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:97:0) after 1m0s
[23:05:06.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:97:0)
[23:05:06.930] Timer tick, asking Buildbucket for the build status
[23:05:07.009] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:07.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:98:0) after 1m0s
[23:06:07.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:98:0)
[23:06:07.029] Timer tick, asking Buildbucket for the build status
[23:06:07.148] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:07.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:99:0) after 1m0s
[23:07:07.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:99:0)
[23:07:07.177] Timer tick, asking Buildbucket for the build status
[23:07:07.261] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:07.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:100:0) after 1m0s
[23:08:07.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:100:0)
[23:08:07.288] Timer tick, asking Buildbucket for the build status
[23:08:07.506] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:07.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:101:0) after 1m0s
[23:09:07.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:101:0)
[23:09:07.527] Timer tick, asking Buildbucket for the build status
[23:09:07.614] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:07.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:102:0) after 1m0s
[23:10:07.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:102:0)
[23:10:07.635] Timer tick, asking Buildbucket for the build status
[23:10:07.693] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:07.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:103:0) after 1m0s
[23:11:07.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:103:0)
[23:11:07.735] Timer tick, asking Buildbucket for the build status
[23:11:07.840] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:07.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:104:0) after 1m0s
[23:12:07.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:104:0)
[23:12:07.878] Timer tick, asking Buildbucket for the build status
[23:12:07.946] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:07.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:105:0) after 1m0s
[23:13:07.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:105:0)
[23:13:07.969] Timer tick, asking Buildbucket for the build status
[23:13:08.172] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:08.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:106:0) after 1m0s
[23:14:08.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:106:0)
[23:14:08.204] Timer tick, asking Buildbucket for the build status
[23:14:08.288] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:08.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:107:0) after 1m0s
[23:15:08.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:107:0)
[23:15:08.354] Timer tick, asking Buildbucket for the build status
[23:15:08.456] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:108:0) after 1m0s
[23:16:08.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:108:0)
[23:16:08.477] Timer tick, asking Buildbucket for the build status
[23:16:08.572] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:08.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:109:0) after 1m0s
[23:17:08.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:109:0)
[23:17:08.598] Timer tick, asking Buildbucket for the build status
[23:17:08.672] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:110:0) after 1m0s
[23:18:08.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:110:0)
[23:18:08.696] Timer tick, asking Buildbucket for the build status
[23:18:08.906] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:08.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:111:0) after 1m0s
[23:19:08.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:111:0)
[23:19:08.931] Timer tick, asking Buildbucket for the build status
[23:19:09.000] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:09.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:112:0) after 1m0s
[23:20:09.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:112:0)
[23:20:09.039] Timer tick, asking Buildbucket for the build status
[23:20:09.114] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:113:0) after 1m0s
[23:21:09.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:113:0)
[23:21:09.137] Timer tick, asking Buildbucket for the build status
[23:21:09.504] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:09.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:114:0) after 1m0s
[23:22:09.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:114:0)
[23:22:09.617] Timer tick, asking Buildbucket for the build status
[23:22:09.681] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:09.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:115:0) after 1m0s
[23:23:09.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:115:0)
[23:23:09.712] Timer tick, asking Buildbucket for the build status
[23:23:10.086] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:10.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:116:0) after 1m0s
[23:24:10.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:116:0)
[23:24:10.108] Timer tick, asking Buildbucket for the build status
[23:24:10.212] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:10.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:117:0) after 1m0s
[23:25:10.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:117:0)
[23:25:10.253] Timer tick, asking Buildbucket for the build status
[23:25:10.406] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:10.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:118:0) after 1m0s
[23:26:10.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:118:0)
[23:26:10.437] Timer tick, asking Buildbucket for the build status
[23:26:10.689] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:119:0) after 1m0s
[23:27:10.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:119:0)
[23:27:10.717] Timer tick, asking Buildbucket for the build status
[23:27:10.836] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:120:0) after 1m0s
[23:28:10.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:120:0)
[23:28:10.862] Timer tick, asking Buildbucket for the build status
[23:28:10.996] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:10.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:121:0) after 1m0s
[23:29:11.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:121:0)
[23:29:11.018] Timer tick, asking Buildbucket for the build status
[23:29:11.082] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:11.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:122:0) after 1m0s
[23:30:11.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:122:0)
[23:30:11.103] Timer tick, asking Buildbucket for the build status
[23:30:11.233] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:11.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:123:0) after 1m0s
[23:31:11.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:123:0)
[23:31:11.332] Timer tick, asking Buildbucket for the build status
[23:31:11.464] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:11.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:124:0) after 1m0s
[23:32:11.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:124:0)
[23:32:11.489] Timer tick, asking Buildbucket for the build status
[23:32:11.589] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:11.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:125:0) after 1m0s
[23:33:11.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:125:0)
[23:33:11.609] Timer tick, asking Buildbucket for the build status
[23:33:11.678] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:11.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:126:0) after 1m0s
[23:34:11.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:126:0)
[23:34:11.698] Timer tick, asking Buildbucket for the build status
[23:34:11.826] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:11.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:127:0) after 1m0s
[23:35:11.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:127:0)
[23:35:11.891] Timer tick, asking Buildbucket for the build status
[23:35:11.963] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:11.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:128:0) after 1m0s
[23:36:12.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:128:0)
[23:36:12.009] Timer tick, asking Buildbucket for the build status
[23:36:12.156] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:12.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:129:0) after 1m0s
[23:37:12.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:129:0)
[23:37:12.247] Timer tick, asking Buildbucket for the build status
[23:37:12.569] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:12.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:130:0) after 1m0s
[23:38:12.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:130:0)
[23:38:12.597] Timer tick, asking Buildbucket for the build status
[23:38:12.846] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:12.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:131:0) after 1m0s
[23:39:12.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:131:0)
[23:39:12.873] Timer tick, asking Buildbucket for the build status
[23:39:12.967] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:12.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:132:0) after 1m0s
[23:40:12.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:132:0)
[23:40:12.988] Timer tick, asking Buildbucket for the build status
[23:40:13.200] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:13.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:133:0) after 1m0s
[23:41:13.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:133:0)
[23:41:13.266] Timer tick, asking Buildbucket for the build status
[23:41:13.382] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:13.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:134:0) after 1m0s
[23:42:13.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:134:0)
[23:42:13.406] Timer tick, asking Buildbucket for the build status
[23:42:13.503] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:13.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:135:0) after 1m0s
[23:43:13.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:135:0)
[23:43:13.531] Timer tick, asking Buildbucket for the build status
[23:43:13.814] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:13.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:136:0) after 1m0s
[23:44:13.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:136:0)
[23:44:13.882] Timer tick, asking Buildbucket for the build status
[23:44:14.014] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:14.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:137:0) after 1m0s
[23:45:14.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:137:0)
[23:45:14.053] Timer tick, asking Buildbucket for the build status
[23:45:14.158] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:14.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:138:0) after 1m0s
[23:46:14.179] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:138:0)
[23:46:14.179] Timer tick, asking Buildbucket for the build status
[23:46:14.263] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:14.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:139:0) after 1m0s
[23:47:14.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:139:0)
[23:47:14.284] Timer tick, asking Buildbucket for the build status
[23:47:14.417] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:14.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:140:0) after 1m0s
[23:48:14.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:140:0)
[23:48:14.439] Timer tick, asking Buildbucket for the build status
[23:48:14.534] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:14.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:141:0) after 1m0s
[23:49:14.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:141:0)
[23:49:14.569] Timer tick, asking Buildbucket for the build status
[23:49:14.636] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:14.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:142:0) after 1m0s
[23:50:14.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:142:0)
[23:50:14.675] Timer tick, asking Buildbucket for the build status
[23:50:14.762] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:14.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:143:0) after 1m0s
[23:51:14.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:143:0)
[23:51:14.779] Timer tick, asking Buildbucket for the build status
[23:51:14.850] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:14.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:144:0) after 1m0s
[23:52:14.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:144:0)
[23:52:14.874] Timer tick, asking Buildbucket for the build status
[23:52:14.962] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:14.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:145:0) after 1m0s
[23:53:14.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:145:0)
[23:53:14.988] Timer tick, asking Buildbucket for the build status
[23:53:15.035] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:15.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:146:0) after 1m0s
[23:54:15.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:146:0)
[23:54:15.054] Timer tick, asking Buildbucket for the build status
[23:54:15.188] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:15.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:147:0) after 1m0s
[23:55:15.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:147:0)
[23:55:15.305] Timer tick, asking Buildbucket for the build status
[23:55:15.386] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:15.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:148:0) after 1m0s
[23:56:15.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:148:0)
[23:56:15.432] Timer tick, asking Buildbucket for the build status
[23:56:15.556] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:15.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:149:0) after 1m0s
[23:57:15.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:149:0)
[23:57:15.575] Timer tick, asking Buildbucket for the build status
[23:57:15.722] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:15.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:150:0) after 1m0s
[23:58:15.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:150:0)
[23:58:15.752] Timer tick, asking Buildbucket for the build status
[23:58:15.934] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:15.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:151:0) after 1m0s
[23:59:15.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:151:0)
[23:59:15.955] Timer tick, asking Buildbucket for the build status
[23:59:16.062] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:16.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:152:0) after 1m0s
[00:00:16.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:152:0)
[00:00:16.697] Timer tick, asking Buildbucket for the build status
[00:00:16.744] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:16.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:153:0) after 1m0s
[00:01:16.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:153:0)
[00:01:16.769] Timer tick, asking Buildbucket for the build status
[00:01:16.841] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:16.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:154:0) after 1m0s
[00:02:16.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:154:0)
[00:02:16.890] Timer tick, asking Buildbucket for the build status
[00:02:16.950] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:16.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:155:0) after 1m0s
[00:03:17.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:155:0)
[00:03:17.038] Timer tick, asking Buildbucket for the build status
[00:03:17.104] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:17.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:156:0) after 1m0s
[00:04:17.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:156:0)
[00:04:17.134] Timer tick, asking Buildbucket for the build status
[00:04:17.257] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:17.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:157:0) after 1m0s
[00:05:17.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:157:0)
[00:05:17.282] Timer tick, asking Buildbucket for the build status
[00:05:17.345] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:17.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:158:0) after 1m0s
[00:06:17.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:158:0)
[00:06:17.369] Timer tick, asking Buildbucket for the build status
[00:06:17.448] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:17.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:159:0) after 1m0s
[00:07:17.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:159:0)
[00:07:17.469] Timer tick, asking Buildbucket for the build status
[00:07:17.588] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:17.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:160:0) after 1m0s
[00:08:17.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:160:0)
[00:08:17.619] Timer tick, asking Buildbucket for the build status
[00:08:17.655] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:17.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:161:0) after 1m0s
[00:09:17.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:161:0)
[00:09:17.804] Timer tick, asking Buildbucket for the build status
[00:09:17.902] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:17.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:162:0) after 1m0s
[00:10:17.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:162:0)
[00:10:17.928] Timer tick, asking Buildbucket for the build status
[00:10:18.005] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:18.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:163:0) after 1m0s
[00:11:18.066] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:163:0)
[00:11:18.066] Timer tick, asking Buildbucket for the build status
[00:11:18.113] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:18.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:164:0) after 1m0s
[00:12:18.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:164:0)
[00:12:18.239] Timer tick, asking Buildbucket for the build status
[00:12:18.294] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:18.294] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:165:0) after 1m0s
[00:13:18.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:165:0)
[00:13:18.364] Timer tick, asking Buildbucket for the build status
[00:13:18.452] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:18.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:166:0) after 1m0s
[00:14:18.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:166:0)
[00:14:18.530] Timer tick, asking Buildbucket for the build status
[00:14:18.587] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:18.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:167:0) after 1m0s
[00:15:18.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:167:0)
[00:15:18.619] Timer tick, asking Buildbucket for the build status
[00:15:18.758] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:18.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:168:0) after 1m0s
[00:16:18.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:168:0)
[00:16:18.782] Timer tick, asking Buildbucket for the build status
[00:16:18.907] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:18.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:169:0) after 1m0s
[00:17:18.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:169:0)
[00:17:18.927] Timer tick, asking Buildbucket for the build status
[00:17:20.033] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:20.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:170:0) after 1m0s
[00:18:20.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:170:0)
[00:18:20.060] Timer tick, asking Buildbucket for the build status
[00:18:20.159] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:20.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:171:0) after 1m0s
[00:19:20.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:171:0)
[00:19:20.184] Timer tick, asking Buildbucket for the build status
[00:19:20.249] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:20.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:172:0) after 1m0s
[00:20:20.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:172:0)
[00:20:20.272] Timer tick, asking Buildbucket for the build status
[00:20:20.329] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:20.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:173:0) after 1m0s
[00:21:20.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:173:0)
[00:21:20.367] Timer tick, asking Buildbucket for the build status
[00:21:20.569] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:20.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:174:0) after 1m0s
[00:22:20.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:174:0)
[00:22:20.596] Timer tick, asking Buildbucket for the build status
[00:22:20.808] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:20.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:175:0) after 1m0s
[00:23:20.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:175:0)
[00:23:20.854] Timer tick, asking Buildbucket for the build status
[00:23:21.151] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:21.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:176:0) after 1m0s
[00:24:21.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:176:0)
[00:24:21.244] Timer tick, asking Buildbucket for the build status
[00:24:21.322] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:21.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:177:0) after 1m0s
[00:25:21.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:177:0)
[00:25:21.346] Timer tick, asking Buildbucket for the build status
[00:25:21.662] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:21.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:178:0) after 1m0s
[00:26:21.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:178:0)
[00:26:21.689] Timer tick, asking Buildbucket for the build status
[00:26:21.813] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:21.813] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:179:0) after 1m0s
[00:27:21.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:179:0)
[00:27:21.837] Timer tick, asking Buildbucket for the build status
[00:27:21.903] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:21.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:180:0) after 1m0s
[00:28:21.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:180:0)
[00:28:21.926] Timer tick, asking Buildbucket for the build status
[00:28:22.081] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:22.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:181:0) after 1m0s
[00:29:22.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:181:0)
[00:29:22.114] Timer tick, asking Buildbucket for the build status
[00:29:22.193] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:22.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:182:0) after 1m0s
[00:30:22.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:182:0)
[00:30:22.229] Timer tick, asking Buildbucket for the build status
[00:30:22.288] Build 8935101937667334816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:22.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9100531999918678512:183:0) after 1m0s
[00:31:02.422] Received PubSub notification, asking Buildbucket for the build status
[00:31:02.509] Build 8935101937667334816: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[00:31:02.510] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1537230661495199",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1537219789782822",
    "experimental": true,
    "failure_reason": "INFRA_FAILURE",
    "id": "8935101937667334816",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"6f1149defa9e40c9bd20512b5560959d381ac202\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"kitchen crashed or got killed\", \"bootstrapperCallStack\": [], \"type\": \"BOOTSTRAPPER_ERROR\"}, \"recipeResult\": null, \"recipeExitCode\": null, \"recipe\": null, \"annotationUrl\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3772-022cb40\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-02248837-us-west1-c-xv5m\"], \"pool\": [\"luci.nacl.toolchain\"]}}, \"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1537219837704567",
    "status": "COMPLETED",
    "status_changed_ts": "1537230661853802",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/6f1149defa9e40c9bd20512b5560959d381ac202",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/6f1149defa9e40c9bd20512b5560959d381ac202",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9100531999918678512",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
      "swarming_tag:buildbucket_bucket:luci.nacl.toolchain",
      "swarming_tag:buildbucket_build_id:8935101937667334816",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/6f1149defa9e40c9bd20512b5560959d381ac202",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/6f1149defa9e40c9bd20512b5560959d381ac202",
      "swarming_tag:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8935101937667334816/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:60",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9100531999918678512",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:aab2657fb2352342b61c0380ef1d346e0cfd7da5",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4002420f3c617510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1537230661854861",
    "url": "https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/4",
    "utcnow_ts": "1537230662450708"
  }
}
[00:31:02.510] Invocation finished in 3h1m14.257808191s with status FAILED