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

• f701a90597fc85979319447c0cd44c3b52201c78 (6 years ago)

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

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

[01:42:36.261] New invocation is queued and will start shortly
[01:42:37.413] Starting the invocation (attempt 1)
[01:42:37.459] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:37.459] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:37.459] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9094627292309527280",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_32-tests-mips\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"f701a90597fc85979319447c0cd44c3b52201c78\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_32-tests-mips",
    "scheduler_invocation_id:9094627292309527280",
    "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
    "user_agent:luci-scheduler",
    "buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78",
    "gitiles_ref:refs/heads/master"
  ]
}
[01:42:38.389] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542850957542316",
    "id": "8929197230302827456",
    "lease_expiration_ts": "1542969758141219",
    "lease_key": "501108575",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_32-tests-mips\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1542850958294922",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4933",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9094627292309527280",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_dimension:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_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_32-tests-mips/4933",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8929197230302827456",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:586d78b1df9b356be85f0824ec8364d9ecbabf90",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78",
      "swarming_tag:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_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/8929197230302827456/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9094627292309527280",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:5b53a70aa8482a54ee03de11951e8067cb630a16",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4151e6da5e912010",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1542850958295051",
    "url": "https://ci.chromium.org/b/8929197230302827456",
    "utcnow_ts": "1542850958374201"
  }
}
[01:42:38.389] Task URL: https://ci.chromium.org/b/8929197230302827456
[01:42:38.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:2:0) after 1m0s
[01:43:38.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:2:0)
[01:43:38.409] Timer tick, asking Buildbucket for the build status
[01:43:38.508] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:38.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:3:0) after 1m0s
[01:44:38.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:3:0)
[01:44:38.569] Timer tick, asking Buildbucket for the build status
[01:44:38.728] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:38.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:4:0) after 1m0s
[01:45:38.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:4:0)
[01:45:38.748] Timer tick, asking Buildbucket for the build status
[01:45:38.784] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:38.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:5:0) after 1m0s
[01:46:38.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:5:0)
[01:46:38.808] Timer tick, asking Buildbucket for the build status
[01:46:38.870] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:38.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:6:0) after 1m0s
[01:47:38.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:6:0)
[01:47:38.889] Timer tick, asking Buildbucket for the build status
[01:47:39.060] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:39.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:7:0) after 1m0s
[01:48:39.080] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:7:0)
[01:48:39.080] Timer tick, asking Buildbucket for the build status
[01:48:39.119] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:39.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:8:0) after 1m0s
[01:49:39.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:8:0)
[01:49:39.146] Timer tick, asking Buildbucket for the build status
[01:49:39.213] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:39.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:9:0) after 1m0s
[01:50:39.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:9:0)
[01:50:39.303] Timer tick, asking Buildbucket for the build status
[01:50:39.379] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:39.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:10:0) after 1m0s
[01:51:39.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:10:0)
[01:51:39.419] Timer tick, asking Buildbucket for the build status
[01:51:39.487] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:39.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:11:0) after 1m0s
[01:52:39.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:11:0)
[01:52:39.570] Timer tick, asking Buildbucket for the build status
[01:52:39.636] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:39.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:12:0) after 1m0s
[01:53:39.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:12:0)
[01:53:39.653] Timer tick, asking Buildbucket for the build status
[01:53:39.687] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:39.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:13:0) after 1m0s
[01:54:39.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:13:0)
[01:54:39.707] Timer tick, asking Buildbucket for the build status
[01:54:39.795] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:39.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:14:0) after 1m0s
[01:55:39.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:14:0)
[01:55:39.814] Timer tick, asking Buildbucket for the build status
[01:55:39.880] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:39.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:15:0) after 1m0s
[01:56:39.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:15:0)
[01:56:39.911] Timer tick, asking Buildbucket for the build status
[01:56:40.035] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:40.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:16:0) after 1m0s
[01:57:40.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:16:0)
[01:57:40.054] Timer tick, asking Buildbucket for the build status
[01:57:40.134] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:40.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:17:0) after 1m0s
[01:58:40.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:17:0)
[01:58:40.153] Timer tick, asking Buildbucket for the build status
[01:58:40.205] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:40.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:18:0) after 1m0s
[01:59:40.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:18:0)
[01:59:40.293] Timer tick, asking Buildbucket for the build status
[01:59:40.471] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:40.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:19:0) after 1m0s
[02:00:40.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:19:0)
[02:00:40.492] Timer tick, asking Buildbucket for the build status
[02:00:40.533] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:40.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:20:0) after 1m0s
[02:01:40.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:20:0)
[02:01:40.554] Timer tick, asking Buildbucket for the build status
[02:01:40.656] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:40.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:21:0) after 1m0s
[02:02:40.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:21:0)
[02:02:40.676] Timer tick, asking Buildbucket for the build status
[02:02:40.740] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:40.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:22:0) after 1m0s
[02:03:40.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:22:0)
[02:03:40.762] Timer tick, asking Buildbucket for the build status
[02:03:41.049] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:41.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:23:0) after 1m0s
[02:04:41.070] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:23:0)
[02:04:41.070] Timer tick, asking Buildbucket for the build status
[02:04:41.239] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:41.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:24:0) after 1m0s
[02:05:41.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:24:0)
[02:05:41.258] Timer tick, asking Buildbucket for the build status
[02:05:41.292] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:41.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:25:0) after 1m0s
[02:06:41.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:25:0)
[02:06:41.311] Timer tick, asking Buildbucket for the build status
[02:06:41.374] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:41.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:26:0) after 1m0s
[02:07:41.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:26:0)
[02:07:41.392] Timer tick, asking Buildbucket for the build status
[02:07:41.437] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:41.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:27:0) after 1m0s
[02:08:41.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:27:0)
[02:08:41.467] Timer tick, asking Buildbucket for the build status
[02:08:41.589] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:41.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:28:0) after 1m0s
[02:09:41.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:28:0)
[02:09:41.607] Timer tick, asking Buildbucket for the build status
[02:09:41.698] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:41.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:29:0) after 1m0s
[02:10:41.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:29:0)
[02:10:41.737] Timer tick, asking Buildbucket for the build status
[02:10:41.819] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:41.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:30:0) after 1m0s
[02:11:41.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:30:0)
[02:11:41.840] Timer tick, asking Buildbucket for the build status
[02:11:41.879] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:41.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:31:0) after 1m0s
[02:12:41.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:31:0)
[02:12:41.902] Timer tick, asking Buildbucket for the build status
[02:12:42.021] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:42.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:32:0) after 1m0s
[02:13:42.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:32:0)
[02:13:42.417] Timer tick, asking Buildbucket for the build status
[02:13:42.569] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:42.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:33:0) after 1m0s
[02:14:42.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:33:0)
[02:14:42.594] Timer tick, asking Buildbucket for the build status
[02:14:42.769] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:42.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:34:0) after 1m0s
[02:15:42.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:34:0)
[02:15:42.789] Timer tick, asking Buildbucket for the build status
[02:15:42.877] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:42.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:35:0) after 1m0s
[02:16:42.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:35:0)
[02:16:42.979] Timer tick, asking Buildbucket for the build status
[02:16:43.054] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:43.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:36:0) after 1m0s
[02:17:43.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:36:0)
[02:17:43.108] Timer tick, asking Buildbucket for the build status
[02:17:43.150] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:43.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:37:0) after 1m0s
[02:18:43.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:37:0)
[02:18:43.169] Timer tick, asking Buildbucket for the build status
[02:18:43.239] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:43.239] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:38:0) after 1m0s
[02:19:43.257] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:38:0)
[02:19:43.257] Timer tick, asking Buildbucket for the build status
[02:19:43.328] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:43.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:39:0) after 1m0s
[02:20:43.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:39:0)
[02:20:43.410] Timer tick, asking Buildbucket for the build status
[02:20:43.457] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:43.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:40:0) after 1m0s
[02:21:43.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:40:0)
[02:21:43.478] Timer tick, asking Buildbucket for the build status
[02:21:43.549] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:43.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:41:0) after 1m0s
[02:22:43.804] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:41:0)
[02:22:43.804] Timer tick, asking Buildbucket for the build status
[02:22:43.887] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:43.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:42:0) after 1m0s
[02:23:43.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:42:0)
[02:23:43.906] Timer tick, asking Buildbucket for the build status
[02:23:43.967] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:43.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:43:0) after 1m0s
[02:24:44.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:43:0)
[02:24:44.034] Timer tick, asking Buildbucket for the build status
[02:24:44.130] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:44.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:44:0) after 1m0s
[02:25:44.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:44:0)
[02:25:44.330] Timer tick, asking Buildbucket for the build status
[02:25:44.413] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:44.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:45:0) after 1m0s
[02:26:44.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:45:0)
[02:26:44.438] Timer tick, asking Buildbucket for the build status
[02:26:44.598] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:44.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:46:0) after 1m0s
[02:27:44.723] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:46:0)
[02:27:44.723] Timer tick, asking Buildbucket for the build status
[02:27:44.762] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:44.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:47:0) after 1m0s
[02:28:44.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:47:0)
[02:28:44.799] Timer tick, asking Buildbucket for the build status
[02:28:44.851] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:44.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:48:0) after 1m0s
[02:29:44.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:48:0)
[02:29:44.874] Timer tick, asking Buildbucket for the build status
[02:29:44.945] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:44.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:49:0) after 1m0s
[02:30:44.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:49:0)
[02:30:44.985] Timer tick, asking Buildbucket for the build status
[02:30:45.043] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:45.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:50:0) after 1m0s
[02:31:45.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:50:0)
[02:31:45.096] Timer tick, asking Buildbucket for the build status
[02:31:45.271] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:45.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:51:0) after 1m0s
[02:32:45.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:51:0)
[02:32:45.302] Timer tick, asking Buildbucket for the build status
[02:32:45.382] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:45.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:52:0) after 1m0s
[02:33:45.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:52:0)
[02:33:45.405] Timer tick, asking Buildbucket for the build status
[02:33:45.441] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:45.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:53:0) after 1m0s
[02:34:45.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:53:0)
[02:34:45.463] Timer tick, asking Buildbucket for the build status
[02:34:45.518] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:45.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:54:0) after 1m0s
[02:35:45.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:54:0)
[02:35:45.895] Timer tick, asking Buildbucket for the build status
[02:35:45.967] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:45.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:55:0) after 1m0s
[02:36:46.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:55:0)
[02:36:46.582] Timer tick, asking Buildbucket for the build status
[02:36:46.668] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:46.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:56:0) after 1m0s
[02:37:47.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:56:0)
[02:37:47.128] Timer tick, asking Buildbucket for the build status
[02:37:47.252] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:47.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:57:0) after 1m0s
[02:38:47.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:57:0)
[02:38:47.725] Timer tick, asking Buildbucket for the build status
[02:38:47.760] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:47.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:58:0) after 1m0s
[02:39:48.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:58:0)
[02:39:48.299] Timer tick, asking Buildbucket for the build status
[02:39:48.375] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:48.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:59:0) after 1m0s
[02:40:48.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:59:0)
[02:40:48.424] Timer tick, asking Buildbucket for the build status
[02:40:48.553] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:48.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:60:0) after 1m0s
[02:41:48.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:60:0)
[02:41:48.680] Timer tick, asking Buildbucket for the build status
[02:41:48.820] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:48.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:61:0) after 1m0s
[02:42:48.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:61:0)
[02:42:48.895] Timer tick, asking Buildbucket for the build status
[02:42:48.977] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:48.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:62:0) after 1m0s
[02:43:49.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:62:0)
[02:43:49.003] Timer tick, asking Buildbucket for the build status
[02:43:49.061] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:49.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:63:0) after 1m0s
[02:44:49.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:63:0)
[02:44:49.089] Timer tick, asking Buildbucket for the build status
[02:44:49.126] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:49.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:64:0) after 1m0s
[02:45:49.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:64:0)
[02:45:49.167] Timer tick, asking Buildbucket for the build status
[02:45:49.282] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:49.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:65:0) after 1m0s
[02:46:49.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:65:0)
[02:46:49.366] Timer tick, asking Buildbucket for the build status
[02:46:49.555] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:49.555] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:66:0) after 1m0s
[02:47:49.586] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:66:0)
[02:47:49.586] Timer tick, asking Buildbucket for the build status
[02:47:49.635] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:49.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:67:0) after 1m0s
[02:48:49.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:67:0)
[02:48:49.789] Timer tick, asking Buildbucket for the build status
[02:48:49.931] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:49.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:68:0) after 1m0s
[02:49:49.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:68:0)
[02:49:49.984] Timer tick, asking Buildbucket for the build status
[02:49:50.181] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:50.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:69:0) after 1m0s
[02:50:50.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:69:0)
[02:50:50.202] Timer tick, asking Buildbucket for the build status
[02:50:50.283] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:50.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:70:0) after 1m0s
[02:51:50.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:70:0)
[02:51:50.308] Timer tick, asking Buildbucket for the build status
[02:51:50.387] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:50.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:71:0) after 1m0s
[02:52:50.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:71:0)
[02:52:50.434] Timer tick, asking Buildbucket for the build status
[02:52:50.508] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:50.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:72:0) after 1m0s
[02:53:50.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:72:0)
[02:53:50.530] Timer tick, asking Buildbucket for the build status
[02:53:50.617] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:50.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:73:0) after 1m0s
[02:54:50.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:73:0)
[02:54:50.646] Timer tick, asking Buildbucket for the build status
[02:54:50.731] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:50.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:74:0) after 1m0s
[02:55:50.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:74:0)
[02:55:50.802] Timer tick, asking Buildbucket for the build status
[02:55:50.890] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:50.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:75:0) after 1m0s
[02:56:50.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:75:0)
[02:56:50.932] Timer tick, asking Buildbucket for the build status
[02:56:51.002] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:51.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:76:0) after 1m0s
[02:57:51.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:76:0)
[02:57:51.043] Timer tick, asking Buildbucket for the build status
[02:57:51.204] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:51.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:77:0) after 1m0s
[02:58:51.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:77:0)
[02:58:51.270] Timer tick, asking Buildbucket for the build status
[02:58:51.373] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:51.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:78:0) after 1m0s
[02:59:51.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:78:0)
[02:59:51.443] Timer tick, asking Buildbucket for the build status
[02:59:51.549] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:51.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:79:0) after 1m0s
[03:00:52.100] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:79:0)
[03:00:52.100] Timer tick, asking Buildbucket for the build status
[03:00:52.274] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:52.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:80:0) after 1m0s
[03:01:52.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:80:0)
[03:01:52.829] Timer tick, asking Buildbucket for the build status
[03:01:53.295] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:53.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:81:0) after 1m0s
[03:02:53.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:81:0)
[03:02:53.323] Timer tick, asking Buildbucket for the build status
[03:02:53.380] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:53.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:82:0) after 1m0s
[03:03:53.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:82:0)
[03:03:53.426] Timer tick, asking Buildbucket for the build status
[03:03:53.587] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:53.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:83:0) after 1m0s
[03:04:53.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:83:0)
[03:04:53.674] Timer tick, asking Buildbucket for the build status
[03:04:53.787] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:53.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:84:0) after 1m0s
[03:05:54.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:84:0)
[03:05:54.031] Timer tick, asking Buildbucket for the build status
[03:05:54.094] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:54.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:85:0) after 1m0s
[03:06:54.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:85:0)
[03:06:54.117] Timer tick, asking Buildbucket for the build status
[03:06:54.177] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:54.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:86:0) after 1m0s
[03:07:54.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:86:0)
[03:07:54.595] Timer tick, asking Buildbucket for the build status
[03:07:54.810] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:54.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:87:0) after 1m0s
[03:08:55.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:87:0)
[03:08:55.432] Timer tick, asking Buildbucket for the build status
[03:08:55.533] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:55.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:88:0) after 1m0s
[03:09:55.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:88:0)
[03:09:55.555] Timer tick, asking Buildbucket for the build status
[03:09:55.624] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:55.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:89:0) after 1m0s
[03:10:55.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:89:0)
[03:10:55.997] Timer tick, asking Buildbucket for the build status
[03:10:56.148] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:56.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:90:0) after 1m0s
[03:11:56.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:90:0)
[03:11:56.626] Timer tick, asking Buildbucket for the build status
[03:11:56.678] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:56.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:91:0) after 1m0s
[03:12:57.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:91:0)
[03:12:57.203] Timer tick, asking Buildbucket for the build status
[03:12:57.343] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:57.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:92:0) after 1m0s
[03:13:58.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:92:0)
[03:13:58.033] Timer tick, asking Buildbucket for the build status
[03:13:58.181] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:58.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:93:0) after 1m0s
[03:14:58.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:93:0)
[03:14:58.797] Timer tick, asking Buildbucket for the build status
[03:14:58.857] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:58.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:94:0) after 1m0s
[03:15:58.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:94:0)
[03:15:58.877] Timer tick, asking Buildbucket for the build status
[03:15:59.074] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:59.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:95:0) after 1m0s
[03:16:59.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:95:0)
[03:16:59.145] Timer tick, asking Buildbucket for the build status
[03:16:59.185] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:59.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:96:0) after 1m0s
[03:17:59.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:96:0)
[03:17:59.234] Timer tick, asking Buildbucket for the build status
[03:17:59.346] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:59.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:97:0) after 1m0s
[03:18:59.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:97:0)
[03:18:59.371] Timer tick, asking Buildbucket for the build status
[03:18:59.434] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:59.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:98:0) after 1m0s
[03:19:59.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:98:0)
[03:19:59.456] Timer tick, asking Buildbucket for the build status
[03:19:59.634] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:59.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:99:0) after 1m0s
[03:20:59.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:99:0)
[03:20:59.897] Timer tick, asking Buildbucket for the build status
[03:21:00.017] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:00.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:100:0) after 1m0s
[03:22:00.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:100:0)
[03:22:00.512] Timer tick, asking Buildbucket for the build status
[03:22:00.681] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:00.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:101:0) after 1m0s
[03:23:00.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:101:0)
[03:23:00.704] Timer tick, asking Buildbucket for the build status
[03:23:00.832] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:00.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:102:0) after 1m0s
[03:24:00.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:102:0)
[03:24:00.900] Timer tick, asking Buildbucket for the build status
[03:24:01.041] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:01.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:103:0) after 1m0s
[03:25:01.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:103:0)
[03:25:01.061] Timer tick, asking Buildbucket for the build status
[03:25:01.154] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:01.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:104:0) after 1m0s
[03:26:01.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:104:0)
[03:26:01.188] Timer tick, asking Buildbucket for the build status
[03:26:01.346] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:01.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:105:0) after 1m0s
[03:27:01.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:105:0)
[03:27:01.365] Timer tick, asking Buildbucket for the build status
[03:27:01.452] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:01.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:106:0) after 1m0s
[03:28:01.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:106:0)
[03:28:01.506] Timer tick, asking Buildbucket for the build status
[03:28:01.572] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:01.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:107:0) after 1m0s
[03:29:01.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:107:0)
[03:29:01.602] Timer tick, asking Buildbucket for the build status
[03:29:01.693] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:01.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:108:0) after 1m0s
[03:30:01.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:108:0)
[03:30:01.728] Timer tick, asking Buildbucket for the build status
[03:30:01.825] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:01.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:109:0) after 1m0s
[03:31:02.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:109:0)
[03:31:02.083] Timer tick, asking Buildbucket for the build status
[03:31:02.170] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:02.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:110:0) after 1m0s
[03:32:02.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:110:0)
[03:32:02.308] Timer tick, asking Buildbucket for the build status
[03:32:02.506] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:02.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:111:0) after 1m0s
[03:33:02.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:111:0)
[03:33:02.527] Timer tick, asking Buildbucket for the build status
[03:33:02.599] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:02.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:112:0) after 1m0s
[03:34:02.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:112:0)
[03:34:02.626] Timer tick, asking Buildbucket for the build status
[03:34:03.197] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:03.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:113:0) after 1m0s
[03:35:03.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:113:0)
[03:35:03.384] Timer tick, asking Buildbucket for the build status
[03:35:03.455] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:03.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:114:0) after 1m0s
[03:36:04.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:114:0)
[03:36:04.085] Timer tick, asking Buildbucket for the build status
[03:36:04.171] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:04.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:115:0) after 1m0s
[03:37:04.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:115:0)
[03:37:04.720] Timer tick, asking Buildbucket for the build status
[03:37:04.781] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:04.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:116:0) after 1m0s
[03:38:05.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:116:0)
[03:38:05.379] Timer tick, asking Buildbucket for the build status
[03:38:05.415] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:05.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:117:0) after 1m0s
[03:39:06.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:117:0)
[03:39:06.063] Timer tick, asking Buildbucket for the build status
[03:39:06.121] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:06.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:118:0) after 1m0s
[03:40:06.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:118:0)
[03:40:06.287] Timer tick, asking Buildbucket for the build status
[03:40:06.380] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:06.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:119:0) after 1m0s
[03:41:06.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:119:0)
[03:41:06.788] Timer tick, asking Buildbucket for the build status
[03:41:06.864] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:06.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:120:0) after 1m0s
[03:42:06.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:120:0)
[03:42:06.900] Timer tick, asking Buildbucket for the build status
[03:42:07.022] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:07.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:121:0) after 1m0s
[03:43:07.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:121:0)
[03:43:07.044] Timer tick, asking Buildbucket for the build status
[03:43:07.209] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:07.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:122:0) after 1m0s
[03:44:07.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:122:0)
[03:44:07.245] Timer tick, asking Buildbucket for the build status
[03:44:07.325] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:07.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:123:0) after 1m0s
[03:45:07.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:123:0)
[03:45:07.353] Timer tick, asking Buildbucket for the build status
[03:45:07.419] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:07.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:124:0) after 1m0s
[03:46:07.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:124:0)
[03:46:07.500] Timer tick, asking Buildbucket for the build status
[03:46:07.652] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:07.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:125:0) after 1m0s
[03:47:07.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:125:0)
[03:47:07.687] Timer tick, asking Buildbucket for the build status
[03:47:07.820] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:07.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:126:0) after 1m0s
[03:48:07.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:126:0)
[03:48:07.885] Timer tick, asking Buildbucket for the build status
[03:48:07.923] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:07.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:127:0) after 1m0s
[03:49:07.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:127:0)
[03:49:07.948] Timer tick, asking Buildbucket for the build status
[03:49:08.003] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:08.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:128:0) after 1m0s
[03:50:08.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:128:0)
[03:50:08.103] Timer tick, asking Buildbucket for the build status
[03:50:08.176] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:08.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:129:0) after 1m0s
[03:51:08.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:129:0)
[03:51:08.200] Timer tick, asking Buildbucket for the build status
[03:51:08.251] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:08.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:130:0) after 1m0s
[03:52:08.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:130:0)
[03:52:08.625] Timer tick, asking Buildbucket for the build status
[03:52:08.674] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:08.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:131:0) after 1m0s
[03:53:08.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:131:0)
[03:53:08.788] Timer tick, asking Buildbucket for the build status
[03:53:08.865] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:08.865] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:132:0) after 1m0s
[03:54:09.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:132:0)
[03:54:09.584] Timer tick, asking Buildbucket for the build status
[03:54:09.685] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:09.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:133:0) after 1m0s
[03:55:09.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:133:0)
[03:55:09.741] Timer tick, asking Buildbucket for the build status
[03:55:09.791] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:09.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:134:0) after 1m0s
[03:56:10.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:134:0)
[03:56:10.114] Timer tick, asking Buildbucket for the build status
[03:56:10.270] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:10.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:135:0) after 1m0s
[03:57:10.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:135:0)
[03:57:10.689] Timer tick, asking Buildbucket for the build status
[03:57:10.777] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:10.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:136:0) after 1m0s
[03:58:11.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:136:0)
[03:58:11.246] Timer tick, asking Buildbucket for the build status
[03:58:11.322] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:11.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:137:0) after 1m0s
[03:59:11.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:137:0)
[03:59:11.345] Timer tick, asking Buildbucket for the build status
[03:59:11.383] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:11.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:138:0) after 1m0s
[04:00:11.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:138:0)
[04:00:11.425] Timer tick, asking Buildbucket for the build status
[04:00:11.501] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:11.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:139:0) after 1m0s
[04:01:11.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:139:0)
[04:01:11.521] Timer tick, asking Buildbucket for the build status
[04:01:11.551] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:11.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:140:0) after 1m0s
[04:02:11.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:140:0)
[04:02:11.981] Timer tick, asking Buildbucket for the build status
[04:02:12.037] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:12.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:141:0) after 1m0s
[04:03:12.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:141:0)
[04:03:12.063] Timer tick, asking Buildbucket for the build status
[04:03:12.118] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:12.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:142:0) after 1m0s
[04:04:12.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:142:0)
[04:04:12.149] Timer tick, asking Buildbucket for the build status
[04:04:12.382] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:12.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:143:0) after 1m0s
[04:05:12.545] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:143:0)
[04:05:12.545] Timer tick, asking Buildbucket for the build status
[04:05:12.622] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:12.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:144:0) after 1m0s
[04:06:13.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:144:0)
[04:06:13.332] Timer tick, asking Buildbucket for the build status
[04:06:13.412] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:13.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:145:0) after 1m0s
[04:07:13.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:145:0)
[04:07:13.960] Timer tick, asking Buildbucket for the build status
[04:07:14.219] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:14.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:146:0) after 1m0s
[04:08:14.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:146:0)
[04:08:14.757] Timer tick, asking Buildbucket for the build status
[04:08:14.833] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:14.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:147:0) after 1m0s
[04:09:15.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:147:0)
[04:09:15.365] Timer tick, asking Buildbucket for the build status
[04:09:15.629] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:15.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:148:0) after 1m0s
[04:10:16.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:148:0)
[04:10:16.225] Timer tick, asking Buildbucket for the build status
[04:10:16.280] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:16.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:149:0) after 1m0s
[04:11:16.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:149:0)
[04:11:16.314] Timer tick, asking Buildbucket for the build status
[04:11:16.354] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:16.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:150:0) after 1m0s
[04:12:16.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:150:0)
[04:12:16.397] Timer tick, asking Buildbucket for the build status
[04:12:16.582] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:16.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:151:0) after 1m0s
[04:13:16.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:151:0)
[04:13:16.610] Timer tick, asking Buildbucket for the build status
[04:13:16.664] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:16.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:152:0) after 1m0s
[04:14:16.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:152:0)
[04:14:16.798] Timer tick, asking Buildbucket for the build status
[04:14:16.885] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:16.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:153:0) after 1m0s
[04:15:17.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:153:0)
[04:15:17.457] Timer tick, asking Buildbucket for the build status
[04:15:17.514] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:17.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:154:0) after 1m0s
[04:16:18.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:154:0)
[04:16:18.057] Timer tick, asking Buildbucket for the build status
[04:16:18.213] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:18.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:155:0) after 1m0s
[04:17:18.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:155:0)
[04:17:18.745] Timer tick, asking Buildbucket for the build status
[04:17:18.811] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:18.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:156:0) after 1m0s
[04:18:18.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:156:0)
[04:18:18.842] Timer tick, asking Buildbucket for the build status
[04:18:18.895] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:18.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:157:0) after 1m0s
[04:19:19.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:157:0)
[04:19:19.018] Timer tick, asking Buildbucket for the build status
[04:19:19.090] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:19.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:158:0) after 1m0s
[04:20:19.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:158:0)
[04:20:19.518] Timer tick, asking Buildbucket for the build status
[04:20:19.630] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:19.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:159:0) after 1m0s
[04:21:19.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:159:0)
[04:21:19.682] Timer tick, asking Buildbucket for the build status
[04:21:19.762] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:19.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:160:0) after 1m0s
[04:22:19.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:160:0)
[04:22:19.792] Timer tick, asking Buildbucket for the build status
[04:22:19.866] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:19.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:161:0) after 1m0s
[04:23:19.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:161:0)
[04:23:19.896] Timer tick, asking Buildbucket for the build status
[04:23:19.996] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:19.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:162:0) after 1m0s
[04:24:20.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:162:0)
[04:24:20.299] Timer tick, asking Buildbucket for the build status
[04:24:20.369] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:20.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:163:0) after 1m0s
[04:25:21.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:163:0)
[04:25:21.081] Timer tick, asking Buildbucket for the build status
[04:25:21.177] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:21.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:164:0) after 1m0s
[04:26:21.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:164:0)
[04:26:21.696] Timer tick, asking Buildbucket for the build status
[04:26:21.762] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:21.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:165:0) after 1m0s
[04:27:22.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:165:0)
[04:27:22.439] Timer tick, asking Buildbucket for the build status
[04:27:22.612] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:22.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:166:0) after 1m0s
[04:28:23.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:166:0)
[04:28:23.076] Timer tick, asking Buildbucket for the build status
[04:28:23.198] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:23.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:167:0) after 1m0s
[04:29:23.746] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:167:0)
[04:29:23.746] Timer tick, asking Buildbucket for the build status
[04:29:23.863] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:23.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:168:0) after 1m0s
[04:30:23.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:168:0)
[04:30:23.901] Timer tick, asking Buildbucket for the build status
[04:30:23.962] Build 8929197230302827456: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:23.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:169:0) after 1m0s
[04:31:03.185] Received PubSub notification, asking Buildbucket for the build status
[04:31:03.312] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:24.512] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:169:0)
[04:31:24.512] Timer tick, asking Buildbucket for the build status
[04:31:24.577] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:24.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:171:0) after 1m0s
[04:32:25.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:171:0)
[04:32:25.482] Timer tick, asking Buildbucket for the build status
[04:32:25.625] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:25.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:172:0) after 1m0s
[04:33:25.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:172:0)
[04:33:25.650] Timer tick, asking Buildbucket for the build status
[04:33:25.728] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:25.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:173:0) after 1m0s
[04:34:26.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:173:0)
[04:34:26.344] Timer tick, asking Buildbucket for the build status
[04:34:26.447] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:26.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:174:0) after 1m0s
[04:35:27.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:174:0)
[04:35:27.013] Timer tick, asking Buildbucket for the build status
[04:35:27.225] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:27.225] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:175:0) after 1m0s
[04:36:27.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:175:0)
[04:36:27.810] Timer tick, asking Buildbucket for the build status
[04:36:27.853] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:27.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:176:0) after 1m0s
[04:37:28.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:176:0)
[04:37:28.333] Timer tick, asking Buildbucket for the build status
[04:37:28.472] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:28.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:177:0) after 1m0s
[04:38:28.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:177:0)
[04:38:28.999] Timer tick, asking Buildbucket for the build status
[04:38:29.070] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:29.070] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:178:0) after 1m0s
[04:39:29.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:178:0)
[04:39:29.598] Timer tick, asking Buildbucket for the build status
[04:39:29.694] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:29.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:179:0) after 1m0s
[04:40:30.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:179:0)
[04:40:30.223] Timer tick, asking Buildbucket for the build status
[04:40:30.305] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:30.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:180:0) after 1m0s
[04:41:30.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:180:0)
[04:41:30.981] Timer tick, asking Buildbucket for the build status
[04:41:31.076] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:31.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:181:0) after 1m0s
[04:42:31.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:181:0)
[04:42:31.402] Timer tick, asking Buildbucket for the build status
[04:42:31.454] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:31.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:182:0) after 1m0s
[04:43:32.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:182:0)
[04:43:32.013] Timer tick, asking Buildbucket for the build status
[04:43:34.277] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:34.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:183:0) after 1m0s
[04:44:34.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:183:0)
[04:44:34.334] Timer tick, asking Buildbucket for the build status
[04:44:34.407] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:34.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:184:0) after 1m0s
[04:45:35.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:184:0)
[04:45:35.152] Timer tick, asking Buildbucket for the build status
[04:45:35.293] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:35.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:185:0) after 1m0s
[04:46:35.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:185:0)
[04:46:35.624] Timer tick, asking Buildbucket for the build status
[04:46:35.847] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:35.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:186:0) after 1m0s
[04:47:36.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:186:0)
[04:47:36.609] Timer tick, asking Buildbucket for the build status
[04:47:36.824] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:36.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:187:0) after 1m0s
[04:48:37.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:187:0)
[04:48:37.113] Timer tick, asking Buildbucket for the build status
[04:48:37.213] Build 8929197230302827456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9094627292309527280:188:0) after 1m0s
[04:49:05.475] Received PubSub notification, asking Buildbucket for the build status
[04:49:05.735] Build 8929197230302827456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:49:05.735] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1542862143919296",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1542850957542316",
    "failure_reason": "BUILD_FAILURE",
    "id": "8929197230302827456",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_32-tests-mips\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure smoke_tests mips32\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3874-22d9c44\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-c-0g9c\"], \"pool\": [\"luci.nacl.toolchain\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 4933, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_32-tests-mips\", \"got_revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1542850957542316, \"tags\": [\"builder:linux-pnacl-x86_32-tests-mips\", \"buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9094627292309527280\", \"scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8929197230302827456\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"f701a90597fc85979319447c0cd44c3b52201c78\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-c-0g9c\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1542861062023818",
    "status": "COMPLETED",
    "status_changed_ts": "1542862144856603",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4933",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9094627292309527280",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_dimension:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_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_32-tests-mips/4933",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8929197230302827456",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:586d78b1df9b356be85f0824ec8364d9ecbabf90",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/f701a90597fc85979319447c0cd44c3b52201c78",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/f701a90597fc85979319447c0cd44c3b52201c78",
      "swarming_tag:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_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/8929197230302827456/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9094627292309527280",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:5b53a70aa8482a54ee03de11951e8067cb630a16",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4151e6da5e912010",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1542862144858900",
    "url": "https://ci.chromium.org/b/8929197230302827456",
    "utcnow_ts": "1542862145710690"
  }
}
[04:49:05.735] Invocation finished in 3h6m29.490582503s with status FAILED