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

• 17af1e818981ebf2c492a678d4d31c9e1ee37fc7 (6 years ago)

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

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

[18:49:21.025] New invocation is queued and will start shortly
[18:49:22.175] Starting the invocation (attempt 1)
[18:49:22.318] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:49:22.318] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:49:22.318] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9093384934471829584",
  "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\":\"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\"}}",
  "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:9093384934471829584",
    "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
    "user_agent:luci-scheduler",
    "buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
    "gitiles_ref:refs/heads/master"
  ]
}
[18:49:23.560] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1544035762505996",
    "id": "8927954872253554464",
    "lease_expiration_ts": "1544154563164937",
    "lease_key": "1295766846",
    "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\": \"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1544035763431309",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4935",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9093384934471829584",
      "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/4935",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8927954872253554464",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "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/8927954872253554464/+/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:9093384934471829584",
      "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:7be98d3a655353173e6c69dca4a3f5562afcd673",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4198858adc9b2610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1544035763431398",
    "url": "https://ci.chromium.org/b/8927954872253554464",
    "utcnow_ts": "1544035763545074"
  }
}
[18:49:23.560] Task URL: https://ci.chromium.org/b/8927954872253554464
[18:49:23.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:2:0) after 1m0s
[18:50:23.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:2:0)
[18:50:23.589] Timer tick, asking Buildbucket for the build status
[18:50:23.659] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:23.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:3:0) after 1m0s
[18:51:23.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:3:0)
[18:51:23.720] Timer tick, asking Buildbucket for the build status
[18:51:23.834] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:23.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:4:0) after 1m0s
[18:52:23.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:4:0)
[18:52:23.850] Timer tick, asking Buildbucket for the build status
[18:52:23.922] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:23.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:5:0) after 1m0s
[18:53:23.961] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:5:0)
[18:53:23.961] Timer tick, asking Buildbucket for the build status
[18:53:24.115] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:24.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:6:0) after 1m0s
[18:54:24.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:6:0)
[18:54:24.152] Timer tick, asking Buildbucket for the build status
[18:54:24.289] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:24.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:7:0) after 1m0s
[18:55:24.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:7:0)
[18:55:24.308] Timer tick, asking Buildbucket for the build status
[18:55:24.407] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:24.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:8:0) after 1m0s
[18:56:24.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:8:0)
[18:56:24.424] Timer tick, asking Buildbucket for the build status
[18:56:24.489] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:24.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:9:0) after 1m0s
[18:57:24.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:9:0)
[18:57:24.506] Timer tick, asking Buildbucket for the build status
[18:57:24.601] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:24.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:10:0) after 1m0s
[18:58:24.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:10:0)
[18:58:24.621] Timer tick, asking Buildbucket for the build status
[18:58:24.654] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:24.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:11:0) after 1m0s
[18:59:24.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:11:0)
[18:59:24.683] Timer tick, asking Buildbucket for the build status
[18:59:24.753] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:24.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:12:0) after 1m0s
[19:00:24.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:12:0)
[19:00:24.772] Timer tick, asking Buildbucket for the build status
[19:00:24.857] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:24.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:13:0) after 1m0s
[19:01:24.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:13:0)
[19:01:24.874] Timer tick, asking Buildbucket for the build status
[19:01:24.941] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:24.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:14:0) after 1m0s
[19:02:24.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:14:0)
[19:02:24.957] Timer tick, asking Buildbucket for the build status
[19:02:24.997] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:24.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:15:0) after 1m0s
[19:03:25.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:15:0)
[19:03:25.024] Timer tick, asking Buildbucket for the build status
[19:03:25.126] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:25.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:16:0) after 1m0s
[19:04:25.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:16:0)
[19:04:25.212] Timer tick, asking Buildbucket for the build status
[19:04:25.249] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:25.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:17:0) after 1m0s
[19:05:25.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:17:0)
[19:05:25.267] Timer tick, asking Buildbucket for the build status
[19:05:25.315] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:25.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:18:0) after 1m0s
[19:06:25.331] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:18:0)
[19:06:25.331] Timer tick, asking Buildbucket for the build status
[19:06:25.509] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:25.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:19:0) after 1m0s
[19:07:25.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:19:0)
[19:07:25.547] Timer tick, asking Buildbucket for the build status
[19:07:25.614] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:25.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:20:0) after 1m0s
[19:08:25.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:20:0)
[19:08:25.631] Timer tick, asking Buildbucket for the build status
[19:08:25.682] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:25.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:21:0) after 1m0s
[19:09:25.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:21:0)
[19:09:25.740] Timer tick, asking Buildbucket for the build status
[19:09:25.782] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:25.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:22:0) after 1m0s
[19:10:25.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:22:0)
[19:10:25.800] Timer tick, asking Buildbucket for the build status
[19:10:25.887] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:25.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:23:0) after 1m0s
[19:11:25.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:23:0)
[19:11:25.914] Timer tick, asking Buildbucket for the build status
[19:11:25.991] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:25.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:24:0) after 1m0s
[19:12:26.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:24:0)
[19:12:26.021] Timer tick, asking Buildbucket for the build status
[19:12:26.087] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:26.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:25:0) after 1m0s
[19:13:26.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:25:0)
[19:13:26.112] Timer tick, asking Buildbucket for the build status
[19:13:26.212] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:26.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:26:0) after 1m0s
[19:14:26.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:26:0)
[19:14:26.237] Timer tick, asking Buildbucket for the build status
[19:14:26.278] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:26.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:27:0) after 1m0s
[19:15:26.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:27:0)
[19:15:26.295] Timer tick, asking Buildbucket for the build status
[19:15:26.369] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:26.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:28:0) after 1m0s
[19:16:26.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:28:0)
[19:16:26.405] Timer tick, asking Buildbucket for the build status
[19:16:26.488] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:26.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:29:0) after 1m0s
[19:17:26.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:29:0)
[19:17:26.548] Timer tick, asking Buildbucket for the build status
[19:17:26.713] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:26.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:30:0) after 1m0s
[19:18:26.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:30:0)
[19:18:26.729] Timer tick, asking Buildbucket for the build status
[19:18:26.942] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:26.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:31:0) after 1m0s
[19:19:26.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:31:0)
[19:19:26.992] Timer tick, asking Buildbucket for the build status
[19:19:27.110] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:27.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:32:0) after 1m0s
[19:20:27.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:32:0)
[19:20:27.143] Timer tick, asking Buildbucket for the build status
[19:20:27.234] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:27.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:33:0) after 1m0s
[19:21:27.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:33:0)
[19:21:27.256] Timer tick, asking Buildbucket for the build status
[19:21:27.336] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:27.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:34:0) after 1m0s
[19:22:27.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:34:0)
[19:22:27.353] Timer tick, asking Buildbucket for the build status
[19:22:27.545] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:27.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:35:0) after 1m0s
[19:23:27.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:35:0)
[19:23:27.566] Timer tick, asking Buildbucket for the build status
[19:23:27.627] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:27.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:36:0) after 1m0s
[19:24:27.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:36:0)
[19:24:27.658] Timer tick, asking Buildbucket for the build status
[19:24:27.776] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:27.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:37:0) after 1m0s
[19:25:27.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:37:0)
[19:25:27.796] Timer tick, asking Buildbucket for the build status
[19:25:27.906] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:27.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:38:0) after 1m0s
[19:26:27.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:38:0)
[19:26:27.925] Timer tick, asking Buildbucket for the build status
[19:26:28.036] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:28.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:39:0) after 1m0s
[19:27:28.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:39:0)
[19:27:28.055] Timer tick, asking Buildbucket for the build status
[19:27:28.127] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:28.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:40:0) after 1m0s
[19:28:28.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:40:0)
[19:28:28.157] Timer tick, asking Buildbucket for the build status
[19:28:28.202] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:28.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:41:0) after 1m0s
[19:29:28.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:41:0)
[19:29:28.229] Timer tick, asking Buildbucket for the build status
[19:29:28.327] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:28.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:42:0) after 1m0s
[19:30:28.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:42:0)
[19:30:28.408] Timer tick, asking Buildbucket for the build status
[19:30:28.544] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:28.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:43:0) after 1m0s
[19:31:28.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:43:0)
[19:31:28.569] Timer tick, asking Buildbucket for the build status
[19:31:28.735] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:28.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:44:0) after 1m0s
[19:32:28.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:44:0)
[19:32:28.771] Timer tick, asking Buildbucket for the build status
[19:32:28.809] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:28.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:45:0) after 1m0s
[19:33:28.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:45:0)
[19:33:28.827] Timer tick, asking Buildbucket for the build status
[19:33:28.898] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:28.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:46:0) after 1m0s
[19:34:28.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:46:0)
[19:34:28.914] Timer tick, asking Buildbucket for the build status
[19:34:28.954] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:28.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:47:0) after 1m0s
[19:35:29.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:47:0)
[19:35:29.011] Timer tick, asking Buildbucket for the build status
[19:35:29.137] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:29.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:48:0) after 1m0s
[19:36:29.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:48:0)
[19:36:29.156] Timer tick, asking Buildbucket for the build status
[19:36:29.218] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:29.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:49:0) after 1m0s
[19:37:29.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:49:0)
[19:37:29.237] Timer tick, asking Buildbucket for the build status
[19:37:29.296] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:29.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:50:0) after 1m0s
[19:38:29.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:50:0)
[19:38:29.323] Timer tick, asking Buildbucket for the build status
[19:38:29.403] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:29.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:51:0) after 1m0s
[19:39:29.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:51:0)
[19:39:29.423] Timer tick, asking Buildbucket for the build status
[19:39:29.590] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:29.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:52:0) after 1m0s
[19:40:29.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:52:0)
[19:40:29.607] Timer tick, asking Buildbucket for the build status
[19:40:29.712] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:29.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:53:0) after 1m0s
[19:41:29.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:53:0)
[19:41:29.728] Timer tick, asking Buildbucket for the build status
[19:41:29.816] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:29.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:54:0) after 1m0s
[19:42:29.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:54:0)
[19:42:29.931] Timer tick, asking Buildbucket for the build status
[19:42:30.146] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:30.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:55:0) after 1m0s
[19:43:30.170] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:55:0)
[19:43:30.170] Timer tick, asking Buildbucket for the build status
[19:43:30.214] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:30.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:56:0) after 1m0s
[19:44:30.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:56:0)
[19:44:30.261] Timer tick, asking Buildbucket for the build status
[19:44:30.318] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:30.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:57:0) after 1m0s
[19:45:30.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:57:0)
[19:45:30.408] Timer tick, asking Buildbucket for the build status
[19:45:30.454] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:30.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:58:0) after 1m0s
[19:46:30.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:58:0)
[19:46:30.473] Timer tick, asking Buildbucket for the build status
[19:46:30.574] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:30.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:59:0) after 1m0s
[19:47:30.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:59:0)
[19:47:30.595] Timer tick, asking Buildbucket for the build status
[19:47:30.741] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:30.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:60:0) after 1m0s
[19:48:30.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:60:0)
[19:48:30.779] Timer tick, asking Buildbucket for the build status
[19:48:30.835] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:30.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:61:0) after 1m0s
[19:49:30.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:61:0)
[19:49:30.854] Timer tick, asking Buildbucket for the build status
[19:49:31.205] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:31.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:62:0) after 1m0s
[19:50:31.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:62:0)
[19:50:31.222] Timer tick, asking Buildbucket for the build status
[19:50:31.254] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:31.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:63:0) after 1m0s
[19:51:31.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:63:0)
[19:51:31.280] Timer tick, asking Buildbucket for the build status
[19:51:31.340] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:31.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:64:0) after 1m0s
[19:52:31.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:64:0)
[19:52:31.366] Timer tick, asking Buildbucket for the build status
[19:52:31.424] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:31.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:65:0) after 1m0s
[19:53:31.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:65:0)
[19:53:31.442] Timer tick, asking Buildbucket for the build status
[19:53:31.495] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:31.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:66:0) after 1m0s
[19:54:31.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:66:0)
[19:54:31.651] Timer tick, asking Buildbucket for the build status
[19:54:31.683] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:31.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:67:0) after 1m0s
[19:55:31.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:67:0)
[19:55:31.716] Timer tick, asking Buildbucket for the build status
[19:55:31.776] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:31.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:68:0) after 1m0s
[19:56:31.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:68:0)
[19:56:31.792] Timer tick, asking Buildbucket for the build status
[19:56:31.896] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:31.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:69:0) after 1m0s
[19:57:32.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:69:0)
[19:57:32.033] Timer tick, asking Buildbucket for the build status
[19:57:32.162] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:32.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:70:0) after 1m0s
[19:58:32.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:70:0)
[19:58:32.323] Timer tick, asking Buildbucket for the build status
[19:58:32.386] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:32.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:71:0) after 1m0s
[19:59:32.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:71:0)
[19:59:32.425] Timer tick, asking Buildbucket for the build status
[19:59:32.487] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:32.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:72:0) after 1m0s
[20:00:32.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:72:0)
[20:00:32.539] Timer tick, asking Buildbucket for the build status
[20:00:32.609] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:32.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:73:0) after 1m0s
[20:01:32.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:73:0)
[20:01:32.659] Timer tick, asking Buildbucket for the build status
[20:01:32.720] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:32.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:74:0) after 1m0s
[20:02:32.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:74:0)
[20:02:32.758] Timer tick, asking Buildbucket for the build status
[20:02:32.805] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:32.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:75:0) after 1m0s
[20:03:32.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:75:0)
[20:03:32.828] Timer tick, asking Buildbucket for the build status
[20:03:32.895] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:32.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:76:0) after 1m0s
[20:04:33.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:76:0)
[20:04:33.033] Timer tick, asking Buildbucket for the build status
[20:04:33.069] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:33.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:77:0) after 1m0s
[20:05:33.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:77:0)
[20:05:33.105] Timer tick, asking Buildbucket for the build status
[20:05:33.163] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:33.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:78:0) after 1m0s
[20:06:33.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:78:0)
[20:06:33.198] Timer tick, asking Buildbucket for the build status
[20:06:33.291] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:33.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:79:0) after 1m0s
[20:07:33.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:79:0)
[20:07:33.314] Timer tick, asking Buildbucket for the build status
[20:07:33.369] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:33.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:80:0) after 1m0s
[20:08:33.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:80:0)
[20:08:33.414] Timer tick, asking Buildbucket for the build status
[20:08:33.467] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:33.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:81:0) after 1m0s
[20:09:33.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:81:0)
[20:09:33.518] Timer tick, asking Buildbucket for the build status
[20:09:33.604] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:33.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:82:0) after 1m0s
[20:10:33.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:82:0)
[20:10:33.623] Timer tick, asking Buildbucket for the build status
[20:10:33.664] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:33.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:83:0) after 1m0s
[20:11:33.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:83:0)
[20:11:33.681] Timer tick, asking Buildbucket for the build status
[20:11:33.738] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:33.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:84:0) after 1m0s
[20:12:33.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:84:0)
[20:12:33.809] Timer tick, asking Buildbucket for the build status
[20:12:33.912] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:33.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:85:0) after 1m0s
[20:13:33.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:85:0)
[20:13:33.935] Timer tick, asking Buildbucket for the build status
[20:13:33.986] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:33.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:86:0) after 1m0s
[20:14:34.006] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:86:0)
[20:14:34.006] Timer tick, asking Buildbucket for the build status
[20:14:34.057] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:34.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:87:0) after 1m0s
[20:15:34.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:87:0)
[20:15:34.084] Timer tick, asking Buildbucket for the build status
[20:15:34.185] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:34.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:88:0) after 1m0s
[20:16:34.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:88:0)
[20:16:34.213] Timer tick, asking Buildbucket for the build status
[20:16:34.349] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:34.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:89:0) after 1m0s
[20:17:34.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:89:0)
[20:17:34.368] Timer tick, asking Buildbucket for the build status
[20:17:34.418] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:34.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:90:0) after 1m0s
[20:18:34.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:90:0)
[20:18:34.438] Timer tick, asking Buildbucket for the build status
[20:18:34.658] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:34.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:91:0) after 1m0s
[20:19:34.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:91:0)
[20:19:34.689] Timer tick, asking Buildbucket for the build status
[20:19:34.771] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:34.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:92:0) after 1m0s
[20:20:34.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:92:0)
[20:20:34.834] Timer tick, asking Buildbucket for the build status
[20:20:34.890] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:34.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:93:0) after 1m0s
[20:21:34.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:93:0)
[20:21:34.931] Timer tick, asking Buildbucket for the build status
[20:21:35.140] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:35.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:94:0) after 1m0s
[20:22:35.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:94:0)
[20:22:35.201] Timer tick, asking Buildbucket for the build status
[20:22:35.237] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:35.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:95:0) after 1m0s
[20:23:35.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:95:0)
[20:23:35.263] Timer tick, asking Buildbucket for the build status
[20:23:35.504] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:35.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:96:0) after 1m0s
[20:24:35.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:96:0)
[20:24:35.583] Timer tick, asking Buildbucket for the build status
[20:24:35.623] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:35.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:97:0) after 1m0s
[20:25:35.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:97:0)
[20:25:35.687] Timer tick, asking Buildbucket for the build status
[20:25:35.762] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:35.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:98:0) after 1m0s
[20:26:35.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:98:0)
[20:26:35.790] Timer tick, asking Buildbucket for the build status
[20:26:35.910] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:35.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:99:0) after 1m0s
[20:27:35.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:99:0)
[20:27:35.951] Timer tick, asking Buildbucket for the build status
[20:27:36.050] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:36.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:100:0) after 1m0s
[20:28:36.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:100:0)
[20:28:36.076] Timer tick, asking Buildbucket for the build status
[20:28:36.130] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:36.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:101:0) after 1m0s
[20:29:36.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:101:0)
[20:29:36.156] Timer tick, asking Buildbucket for the build status
[20:29:36.226] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:36.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:102:0) after 1m0s
[20:30:36.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:102:0)
[20:30:36.246] Timer tick, asking Buildbucket for the build status
[20:30:36.282] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:36.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:103:0) after 1m0s
[20:31:36.315] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:103:0)
[20:31:36.315] Timer tick, asking Buildbucket for the build status
[20:31:36.394] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:36.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:104:0) after 1m0s
[20:34:42.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:104:0)
[20:34:42.862] Timer tick, asking Buildbucket for the build status
[20:34:42.980] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:42.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:105:0) after 1m0s
[20:35:43.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:105:0)
[20:35:43.001] Timer tick, asking Buildbucket for the build status
[20:35:43.066] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:43.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:106:0) after 1m0s
[20:36:43.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:106:0)
[20:36:43.085] Timer tick, asking Buildbucket for the build status
[20:36:43.117] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:43.117] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:107:0) after 1m0s
[20:37:43.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:107:0)
[20:37:43.141] Timer tick, asking Buildbucket for the build status
[20:37:43.265] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:43.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:108:0) after 1m0s
[20:38:43.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:108:0)
[20:38:43.289] Timer tick, asking Buildbucket for the build status
[20:38:43.395] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:43.395] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:109:0) after 1m0s
[20:39:43.422] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:109:0)
[20:39:43.422] Timer tick, asking Buildbucket for the build status
[20:39:43.479] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:43.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:110:0) after 1m0s
[20:40:43.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:110:0)
[20:40:43.504] Timer tick, asking Buildbucket for the build status
[20:40:43.539] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:43.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:111:0) after 1m0s
[20:41:43.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:111:0)
[20:41:43.569] Timer tick, asking Buildbucket for the build status
[20:41:43.637] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:43.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:112:0) after 1m0s
[20:42:43.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:112:0)
[20:42:43.659] Timer tick, asking Buildbucket for the build status
[20:42:43.696] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:43.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:113:0) after 1m0s
[20:43:43.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:113:0)
[20:43:43.713] Timer tick, asking Buildbucket for the build status
[20:43:43.792] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:43.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:114:0) after 1m0s
[20:44:43.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:114:0)
[20:44:43.809] Timer tick, asking Buildbucket for the build status
[20:44:43.869] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:43.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:115:0) after 1m0s
[20:45:43.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:115:0)
[20:45:43.890] Timer tick, asking Buildbucket for the build status
[20:45:43.953] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:43.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:116:0) after 1m0s
[20:46:43.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:116:0)
[20:46:43.980] Timer tick, asking Buildbucket for the build status
[20:46:44.043] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:44.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:117:0) after 1m0s
[20:47:44.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:117:0)
[20:47:44.064] Timer tick, asking Buildbucket for the build status
[20:47:44.172] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:44.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:118:0) after 1m0s
[20:48:44.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:118:0)
[20:48:44.276] Timer tick, asking Buildbucket for the build status
[20:48:44.327] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:44.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:119:0) after 1m0s
[20:49:44.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:119:0)
[20:49:44.351] Timer tick, asking Buildbucket for the build status
[20:49:44.467] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:44.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:120:0) after 1m0s
[20:50:44.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:120:0)
[20:50:44.491] Timer tick, asking Buildbucket for the build status
[20:50:44.539] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:44.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:121:0) after 1m0s
[20:51:44.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:121:0)
[20:51:44.563] Timer tick, asking Buildbucket for the build status
[20:51:44.634] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:44.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:122:0) after 1m0s
[20:52:44.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:122:0)
[20:52:44.656] Timer tick, asking Buildbucket for the build status
[20:52:44.857] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:44.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:123:0) after 1m0s
[20:53:44.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:123:0)
[20:53:44.885] Timer tick, asking Buildbucket for the build status
[20:53:45.116] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:45.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:124:0) after 1m0s
[20:54:45.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:124:0)
[20:54:45.167] Timer tick, asking Buildbucket for the build status
[20:54:45.237] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:45.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:125:0) after 1m0s
[20:55:45.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:125:0)
[20:55:45.288] Timer tick, asking Buildbucket for the build status
[20:55:45.362] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:45.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:126:0) after 1m0s
[20:56:45.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:126:0)
[20:56:45.395] Timer tick, asking Buildbucket for the build status
[20:56:45.446] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:45.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:127:0) after 1m0s
[20:57:45.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:127:0)
[20:57:45.483] Timer tick, asking Buildbucket for the build status
[20:57:45.602] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:45.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:128:0) after 1m0s
[20:58:45.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:128:0)
[20:58:45.626] Timer tick, asking Buildbucket for the build status
[20:58:45.718] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:45.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:129:0) after 1m0s
[20:59:45.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:129:0)
[20:59:45.736] Timer tick, asking Buildbucket for the build status
[20:59:45.779] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:45.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:130:0) after 1m0s
[21:00:45.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:130:0)
[21:00:45.812] Timer tick, asking Buildbucket for the build status
[21:00:45.966] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:45.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:131:0) after 1m0s
[21:01:45.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:131:0)
[21:01:45.984] Timer tick, asking Buildbucket for the build status
[21:01:46.050] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:46.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:132:0) after 1m0s
[21:02:46.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:132:0)
[21:02:46.086] Timer tick, asking Buildbucket for the build status
[21:02:46.139] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:46.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:133:0) after 1m0s
[21:03:46.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:133:0)
[21:03:46.162] Timer tick, asking Buildbucket for the build status
[21:03:46.222] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:46.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:134:0) after 1m0s
[21:04:46.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:134:0)
[21:04:46.240] Timer tick, asking Buildbucket for the build status
[21:04:46.311] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:46.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:135:0) after 1m0s
[21:05:46.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:135:0)
[21:05:46.335] Timer tick, asking Buildbucket for the build status
[21:05:46.530] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:46.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:136:0) after 1m0s
[21:06:46.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:136:0)
[21:06:46.569] Timer tick, asking Buildbucket for the build status
[21:06:46.615] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:46.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:137:0) after 1m0s
[21:07:46.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:137:0)
[21:07:46.642] Timer tick, asking Buildbucket for the build status
[21:07:46.690] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:46.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:138:0) after 1m0s
[21:08:46.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:138:0)
[21:08:46.762] Timer tick, asking Buildbucket for the build status
[21:08:46.859] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:46.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:139:0) after 1m0s
[21:09:46.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:139:0)
[21:09:46.882] Timer tick, asking Buildbucket for the build status
[21:09:46.959] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:46.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:140:0) after 1m0s
[21:10:46.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:140:0)
[21:10:46.981] Timer tick, asking Buildbucket for the build status
[21:10:47.032] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:47.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:141:0) after 1m0s
[21:11:47.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:141:0)
[21:11:47.059] Timer tick, asking Buildbucket for the build status
[21:11:47.119] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:47.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:142:0) after 1m0s
[21:12:47.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:142:0)
[21:12:47.140] Timer tick, asking Buildbucket for the build status
[21:12:47.314] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:47.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:143:0) after 1m0s
[21:13:47.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:143:0)
[21:13:47.337] Timer tick, asking Buildbucket for the build status
[21:13:47.380] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:47.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:144:0) after 1m0s
[21:14:47.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:144:0)
[21:14:47.408] Timer tick, asking Buildbucket for the build status
[21:14:47.455] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:47.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:145:0) after 1m0s
[21:15:47.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:145:0)
[21:15:47.484] Timer tick, asking Buildbucket for the build status
[21:15:47.579] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:47.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:146:0) after 1m0s
[21:16:47.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:146:0)
[21:16:47.601] Timer tick, asking Buildbucket for the build status
[21:16:47.703] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:47.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:147:0) after 1m0s
[21:17:47.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:147:0)
[21:17:47.730] Timer tick, asking Buildbucket for the build status
[21:17:47.802] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:47.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:148:0) after 1m0s
[21:18:47.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:148:0)
[21:18:47.823] Timer tick, asking Buildbucket for the build status
[21:18:47.884] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:47.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:149:0) after 1m0s
[21:19:47.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:149:0)
[21:19:47.927] Timer tick, asking Buildbucket for the build status
[21:19:48.213] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:48.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:150:0) after 1m0s
[21:20:48.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:150:0)
[21:20:48.329] Timer tick, asking Buildbucket for the build status
[21:20:48.386] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:48.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:151:0) after 1m0s
[21:21:48.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:151:0)
[21:21:48.451] Timer tick, asking Buildbucket for the build status
[21:21:48.607] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:48.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:152:0) after 1m0s
[21:22:48.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:152:0)
[21:22:48.631] Timer tick, asking Buildbucket for the build status
[21:22:48.694] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:48.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:153:0) after 1m0s
[21:23:48.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:153:0)
[21:23:48.733] Timer tick, asking Buildbucket for the build status
[21:23:49.021] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:49.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:154:0) after 1m0s
[21:24:49.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:154:0)
[21:24:49.042] Timer tick, asking Buildbucket for the build status
[21:24:49.074] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:49.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:155:0) after 1m0s
[21:25:49.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:155:0)
[21:25:49.107] Timer tick, asking Buildbucket for the build status
[21:25:49.152] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:49.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:156:0) after 1m0s
[21:26:49.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:156:0)
[21:26:49.178] Timer tick, asking Buildbucket for the build status
[21:26:49.216] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:49.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:157:0) after 1m0s
[21:27:49.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:157:0)
[21:27:49.292] Timer tick, asking Buildbucket for the build status
[21:27:49.389] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:49.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:158:0) after 1m0s
[21:28:49.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:158:0)
[21:28:49.413] Timer tick, asking Buildbucket for the build status
[21:28:49.536] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:49.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:159:0) after 1m0s
[21:29:49.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:159:0)
[21:29:49.560] Timer tick, asking Buildbucket for the build status
[21:29:49.640] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:49.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:160:0) after 1m0s
[21:30:49.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:160:0)
[21:30:49.661] Timer tick, asking Buildbucket for the build status
[21:30:49.707] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:49.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:161:0) after 1m0s
[21:31:49.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:161:0)
[21:31:49.726] Timer tick, asking Buildbucket for the build status
[21:31:49.854] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:49.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:162:0) after 1m0s
[21:32:49.888] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:162:0)
[21:32:49.888] Timer tick, asking Buildbucket for the build status
[21:32:49.923] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:49.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:163:0) after 1m0s
[21:33:49.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:163:0)
[21:33:49.991] Timer tick, asking Buildbucket for the build status
[21:33:50.083] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:50.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:164:0) after 1m0s
[21:34:50.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:164:0)
[21:34:50.147] Timer tick, asking Buildbucket for the build status
[21:34:50.228] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:50.228] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:165:0) after 1m0s
[21:35:50.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:165:0)
[21:35:50.255] Timer tick, asking Buildbucket for the build status
[21:35:50.890] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:50.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:166:0) after 1m0s
[21:36:51.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:166:0)
[21:36:51.004] Timer tick, asking Buildbucket for the build status
[21:36:51.065] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:51.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:167:0) after 1m0s
[21:37:51.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:167:0)
[21:37:51.089] Timer tick, asking Buildbucket for the build status
[21:37:51.218] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:51.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:168:0) after 1m0s
[21:38:51.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:168:0)
[21:38:51.243] Timer tick, asking Buildbucket for the build status
[21:38:51.284] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:51.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:169:0) after 1m0s
[21:39:51.310] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:169:0)
[21:39:51.310] Timer tick, asking Buildbucket for the build status
[21:39:51.346] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:51.346] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:170:0) after 1m0s
[21:40:51.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:170:0)
[21:40:51.373] Timer tick, asking Buildbucket for the build status
[21:40:51.441] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:51.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:171:0) after 1m0s
[21:41:51.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:171:0)
[21:41:51.460] Timer tick, asking Buildbucket for the build status
[21:41:51.511] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:51.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:172:0) after 1m0s
[21:42:51.549] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:172:0)
[21:42:51.549] Timer tick, asking Buildbucket for the build status
[21:42:51.626] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:51.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:173:0) after 1m0s
[21:43:51.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:173:0)
[21:43:51.651] Timer tick, asking Buildbucket for the build status
[21:43:51.720] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:51.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:174:0) after 1m0s
[21:44:51.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:174:0)
[21:44:51.743] Timer tick, asking Buildbucket for the build status
[21:44:51.789] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:51.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:175:0) after 1m0s
[21:45:51.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:175:0)
[21:45:51.810] Timer tick, asking Buildbucket for the build status
[21:45:51.885] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:51.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:176:0) after 1m0s
[21:46:51.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:176:0)
[21:46:51.911] Timer tick, asking Buildbucket for the build status
[21:46:51.950] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:51.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:177:0) after 1m0s
[21:47:51.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:177:0)
[21:47:51.979] Timer tick, asking Buildbucket for the build status
[21:47:52.036] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:52.036] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:178:0) after 1m0s
[21:48:52.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:178:0)
[21:48:52.077] Timer tick, asking Buildbucket for the build status
[21:48:52.131] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:52.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:179:0) after 1m0s
[21:49:52.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:179:0)
[21:49:52.157] Timer tick, asking Buildbucket for the build status
[21:49:52.219] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:52.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:180:0) after 1m0s
[21:50:52.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:180:0)
[21:50:52.255] Timer tick, asking Buildbucket for the build status
[21:50:52.303] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:52.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:181:0) after 1m0s
[21:51:52.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:181:0)
[21:51:52.328] Timer tick, asking Buildbucket for the build status
[21:51:52.409] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:52.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:182:0) after 1m0s
[21:52:52.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:182:0)
[21:52:52.471] Timer tick, asking Buildbucket for the build status
[21:52:52.532] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:52.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:183:0) after 1m0s
[21:53:52.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:183:0)
[21:53:52.563] Timer tick, asking Buildbucket for the build status
[21:53:52.728] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:52.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:184:0) after 1m0s
[21:54:52.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:184:0)
[21:54:52.751] Timer tick, asking Buildbucket for the build status
[21:54:52.797] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:52.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:185:0) after 1m0s
[21:55:52.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:185:0)
[21:55:52.820] Timer tick, asking Buildbucket for the build status
[21:55:52.931] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:52.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:186:0) after 1m0s
[21:56:52.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:186:0)
[21:56:52.955] Timer tick, asking Buildbucket for the build status
[21:56:53.074] Build 8927954872253554464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:53.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:187:0) after 1m0s
[21:57:17.080] Received PubSub notification, asking Buildbucket for the build status
[21:57:17.128] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:53.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:187:0)
[21:57:53.099] Timer tick, asking Buildbucket for the build status
[21:57:53.167] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:53.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:189:0) after 1m0s
[21:58:53.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:189:0)
[21:58:53.198] Timer tick, asking Buildbucket for the build status
[21:58:53.303] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:53.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:190:0) after 1m0s
[21:59:53.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:190:0)
[21:59:53.325] Timer tick, asking Buildbucket for the build status
[21:59:53.396] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:53.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:191:0) after 1m0s
[22:00:53.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:191:0)
[22:00:53.435] Timer tick, asking Buildbucket for the build status
[22:00:53.485] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:53.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:192:0) after 1m0s
[22:01:53.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:192:0)
[22:01:53.511] Timer tick, asking Buildbucket for the build status
[22:01:53.566] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:53.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:193:0) after 1m0s
[22:02:53.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:193:0)
[22:02:53.595] Timer tick, asking Buildbucket for the build status
[22:02:53.671] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:53.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:194:0) after 1m0s
[22:03:53.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:194:0)
[22:03:53.697] Timer tick, asking Buildbucket for the build status
[22:03:53.749] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:53.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:195:0) after 1m0s
[22:04:53.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:195:0)
[22:04:53.787] Timer tick, asking Buildbucket for the build status
[22:04:54.061] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:54.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:196:0) after 1m0s
[22:05:54.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:196:0)
[22:05:54.083] Timer tick, asking Buildbucket for the build status
[22:05:54.150] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:54.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:197:0) after 1m0s
[22:06:54.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:197:0)
[22:06:54.175] Timer tick, asking Buildbucket for the build status
[22:06:54.246] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:54.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:198:0) after 1m0s
[22:07:54.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:198:0)
[22:07:54.271] Timer tick, asking Buildbucket for the build status
[22:07:54.322] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:54.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:199:0) after 1m0s
[22:08:54.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:199:0)
[22:08:54.425] Timer tick, asking Buildbucket for the build status
[22:08:54.567] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:54.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:200:0) after 1m0s
[22:09:54.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:200:0)
[22:09:54.636] Timer tick, asking Buildbucket for the build status
[22:09:54.879] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:54.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:201:0) after 1m0s
[22:10:54.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:201:0)
[22:10:54.915] Timer tick, asking Buildbucket for the build status
[22:10:54.986] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:54.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:202:0) after 1m0s
[22:11:55.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:202:0)
[22:11:55.013] Timer tick, asking Buildbucket for the build status
[22:11:55.114] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:55.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:203:0) after 1m0s
[22:12:55.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:203:0)
[22:12:55.143] Timer tick, asking Buildbucket for the build status
[22:12:55.204] Build 8927954872253554464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:55.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9093384934471829584:204:0) after 1m0s
[22:13:10.823] Received PubSub notification, asking Buildbucket for the build status
[22:13:10.858] Build 8927954872253554464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:13:10.858] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1544047989099368",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1544035762505996",
    "id": "8927954872253554464",
    "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\": \"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"], \"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\": [\"3910-a71d441\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-west1-b-1jxx\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 4935, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_32-tests-mips\", \"got_revision\": \"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1544035762505996, \"tags\": [\"builder:linux-pnacl-x86_32-tests-mips\", \"buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093384934471829584\", \"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\": \"8927954872253554464\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"17af1e818981ebf2c492a678d4d31c9e1ee37fc7\", \"bot_id\": \"gce-trusty-e833d7b0-us-west1-b-1jxx\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1544047036284978",
    "status": "COMPLETED",
    "status_changed_ts": "1544047990435217",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4935",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9093384934471829584",
      "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/4935",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8927954872253554464",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/17af1e818981ebf2c492a678d4d31c9e1ee37fc7",
      "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/8927954872253554464/+/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:9093384934471829584",
      "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:7be98d3a655353173e6c69dca4a3f5562afcd673",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4198858adc9b2610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1544047990436920",
    "url": "https://ci.chromium.org/b/8927954872253554464",
    "utcnow_ts": "1544047990848849"
  }
}
[22:13:10.858] Invocation finished in 3h23m49.851958028s with status SUCCEEDED