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

• 1ea07c56ac9b57c30eb784ab2af582af0cdd4b08 (6 years ago)

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

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

[18:37:36.094] New invocation is queued and will start shortly
[18:37:38.061] Starting the invocation (attempt 1)
[18:37:38.127] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:37:38.127] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:37:38.127] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9090667764655210928",
  "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\":\"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
  "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:9090667764655210928",
    "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
    "user_agent:luci-scheduler",
    "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
    "gitiles_ref:refs/heads/master"
  ]
}
[18:37:39.883] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1546627058263990",
    "id": "8925237701712770224",
    "lease_expiration_ts": "1546745859415296",
    "lease_key": "1290762354",
    "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\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1546627059711997",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4948",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9090667764655210928",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_dimension:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4948",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8925237701712770224",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8925237701712770224/+/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:9090667764655210928",
      "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:8df9132fd2891579a00ae465513e90e9036bee95",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4232f994e13cd810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1546627059712122",
    "url": "https://ci.chromium.org/b/8925237701712770224",
    "utcnow_ts": "1546627059836563"
  }
}
[18:37:39.883] Task URL: https://ci.chromium.org/b/8925237701712770224
[18:37:39.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:2:0) after 1m0s
[18:38:40.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:2:0)
[18:38:40.510] Timer tick, asking Buildbucket for the build status
[18:38:40.570] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:40.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:3:0) after 1m0s
[18:39:40.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:3:0)
[18:39:40.612] Timer tick, asking Buildbucket for the build status
[18:39:40.668] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:40.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:4:0) after 1m0s
[18:40:40.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:4:0)
[18:40:40.689] Timer tick, asking Buildbucket for the build status
[18:40:40.749] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:40.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:5:0) after 1m0s
[18:41:40.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:5:0)
[18:41:40.770] Timer tick, asking Buildbucket for the build status
[18:41:40.844] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:40.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:6:0) after 1m0s
[18:42:41.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:6:0)
[18:42:41.029] Timer tick, asking Buildbucket for the build status
[18:42:41.265] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:41.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:7:0) after 1m0s
[18:43:41.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:7:0)
[18:43:41.320] Timer tick, asking Buildbucket for the build status
[18:43:41.407] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:41.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:8:0) after 1m0s
[18:44:41.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:8:0)
[18:44:41.425] Timer tick, asking Buildbucket for the build status
[18:44:41.466] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:41.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:9:0) after 1m0s
[18:45:41.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:9:0)
[18:45:41.505] Timer tick, asking Buildbucket for the build status
[18:45:41.585] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:41.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:10:0) after 1m0s
[18:46:41.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:10:0)
[18:46:41.603] Timer tick, asking Buildbucket for the build status
[18:46:42.010] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:42.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:11:0) after 1m0s
[18:47:42.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:11:0)
[18:47:42.028] Timer tick, asking Buildbucket for the build status
[18:47:42.075] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:42.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:12:0) after 1m0s
[18:48:42.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:12:0)
[18:48:42.439] Timer tick, asking Buildbucket for the build status
[18:48:42.505] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:42.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:13:0) after 1m0s
[18:49:42.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:13:0)
[18:49:42.522] Timer tick, asking Buildbucket for the build status
[18:49:42.574] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:42.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:14:0) after 1m0s
[18:50:42.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:14:0)
[18:50:42.840] Timer tick, asking Buildbucket for the build status
[18:50:42.910] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:42.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:15:0) after 1m0s
[18:51:42.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:15:0)
[18:51:42.934] Timer tick, asking Buildbucket for the build status
[18:51:42.969] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:42.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:16:0) after 1m0s
[18:52:42.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:16:0)
[18:52:42.989] Timer tick, asking Buildbucket for the build status
[18:52:43.063] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:43.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:17:0) after 1m0s
[18:53:43.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:17:0)
[18:53:43.081] Timer tick, asking Buildbucket for the build status
[18:53:43.146] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:43.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:18:0) after 1m0s
[18:54:43.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:18:0)
[18:54:43.166] Timer tick, asking Buildbucket for the build status
[18:54:43.338] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:43.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:19:0) after 1m0s
[18:55:43.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:19:0)
[18:55:43.356] Timer tick, asking Buildbucket for the build status
[18:55:43.390] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:43.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:20:0) after 1m0s
[18:56:43.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:20:0)
[18:56:43.406] Timer tick, asking Buildbucket for the build status
[18:56:43.463] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:43.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:21:0) after 1m0s
[18:57:43.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:21:0)
[18:57:43.481] Timer tick, asking Buildbucket for the build status
[18:57:43.541] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:43.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:22:0) after 1m0s
[18:58:43.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:22:0)
[18:58:43.560] Timer tick, asking Buildbucket for the build status
[18:58:43.615] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:43.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:23:0) after 1m0s
[18:59:43.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:23:0)
[18:59:43.637] Timer tick, asking Buildbucket for the build status
[18:59:43.693] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:43.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:24:0) after 1m0s
[19:00:43.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:24:0)
[19:00:43.712] Timer tick, asking Buildbucket for the build status
[19:00:43.768] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:43.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:25:0) after 1m0s
[19:01:43.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:25:0)
[19:01:43.844] Timer tick, asking Buildbucket for the build status
[19:01:43.902] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:43.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:26:0) after 1m0s
[19:02:43.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:26:0)
[19:02:43.931] Timer tick, asking Buildbucket for the build status
[19:02:44.034] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:44.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:27:0) after 1m0s
[19:03:44.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:27:0)
[19:03:44.102] Timer tick, asking Buildbucket for the build status
[19:03:44.189] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:44.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:28:0) after 1m0s
[19:04:44.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:28:0)
[19:04:44.218] Timer tick, asking Buildbucket for the build status
[19:04:44.402] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:44.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:29:0) after 1m0s
[19:05:44.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:29:0)
[19:05:44.420] Timer tick, asking Buildbucket for the build status
[19:05:44.497] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:44.497] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:30:0) after 1m0s
[19:06:44.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:30:0)
[19:06:44.625] Timer tick, asking Buildbucket for the build status
[19:06:44.737] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:44.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:31:0) after 1m0s
[19:07:44.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:31:0)
[19:07:44.756] Timer tick, asking Buildbucket for the build status
[19:07:44.803] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:44.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:32:0) after 1m0s
[19:08:44.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:32:0)
[19:08:44.821] Timer tick, asking Buildbucket for the build status
[19:08:45.256] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:45.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:33:0) after 1m0s
[19:09:45.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:33:0)
[19:09:45.289] Timer tick, asking Buildbucket for the build status
[19:09:45.458] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:45.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:34:0) after 1m0s
[19:10:45.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:34:0)
[19:10:45.476] Timer tick, asking Buildbucket for the build status
[19:10:45.541] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:10:45.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:35:0) after 1m0s
[19:11:45.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:35:0)
[19:11:45.561] Timer tick, asking Buildbucket for the build status
[19:11:45.609] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:11:45.609] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:36:0) after 1m0s
[19:12:45.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:36:0)
[19:12:45.628] Timer tick, asking Buildbucket for the build status
[19:12:45.719] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:12:45.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:37:0) after 1m0s
[19:13:45.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:37:0)
[19:13:45.785] Timer tick, asking Buildbucket for the build status
[19:13:45.835] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:13:45.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:38:0) after 1m0s
[19:14:45.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:38:0)
[19:14:45.854] Timer tick, asking Buildbucket for the build status
[19:14:45.922] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:14:45.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:39:0) after 1m0s
[19:15:45.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:39:0)
[19:15:45.966] Timer tick, asking Buildbucket for the build status
[19:15:46.016] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:15:46.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:40:0) after 1m0s
[19:16:46.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:40:0)
[19:16:46.067] Timer tick, asking Buildbucket for the build status
[19:16:46.134] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:16:46.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:41:0) after 1m0s
[19:17:46.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:41:0)
[19:17:46.161] Timer tick, asking Buildbucket for the build status
[19:17:46.318] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:17:46.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:42:0) after 1m0s
[19:18:46.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:42:0)
[19:18:46.337] Timer tick, asking Buildbucket for the build status
[19:18:46.402] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:18:46.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:43:0) after 1m0s
[19:19:46.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:43:0)
[19:19:46.424] Timer tick, asking Buildbucket for the build status
[19:19:46.478] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:19:46.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:44:0) after 1m0s
[19:20:46.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:44:0)
[19:20:46.499] Timer tick, asking Buildbucket for the build status
[19:20:46.921] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:20:46.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:45:0) after 1m0s
[19:21:46.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:45:0)
[19:21:46.944] Timer tick, asking Buildbucket for the build status
[19:21:47.008] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:21:47.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:46:0) after 1m0s
[19:22:47.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:46:0)
[19:22:47.029] Timer tick, asking Buildbucket for the build status
[19:22:47.083] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:22:47.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:47:0) after 1m0s
[19:23:47.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:47:0)
[19:23:47.189] Timer tick, asking Buildbucket for the build status
[19:23:47.305] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:23:47.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:48:0) after 1m0s
[19:24:47.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:48:0)
[19:24:47.386] Timer tick, asking Buildbucket for the build status
[19:24:47.448] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:24:47.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:49:0) after 1m0s
[19:25:47.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:49:0)
[19:25:47.464] Timer tick, asking Buildbucket for the build status
[19:25:47.510] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:25:47.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:50:0) after 1m0s
[19:26:47.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:50:0)
[19:26:47.531] Timer tick, asking Buildbucket for the build status
[19:26:47.578] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:26:47.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:51:0) after 1m0s
[19:27:47.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:51:0)
[19:27:47.657] Timer tick, asking Buildbucket for the build status
[19:27:47.749] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:27:47.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:52:0) after 1m0s
[19:28:47.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:52:0)
[19:28:47.772] Timer tick, asking Buildbucket for the build status
[19:28:47.830] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:28:47.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:53:0) after 1m0s
[19:29:47.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:53:0)
[19:29:47.857] Timer tick, asking Buildbucket for the build status
[19:29:47.904] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:29:47.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:54:0) after 1m0s
[19:30:47.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:54:0)
[19:30:47.926] Timer tick, asking Buildbucket for the build status
[19:30:47.982] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:30:47.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:55:0) after 1m0s
[19:31:48.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:55:0)
[19:31:48.013] Timer tick, asking Buildbucket for the build status
[19:31:48.043] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:31:48.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:56:0) after 1m0s
[19:32:48.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:56:0)
[19:32:48.064] Timer tick, asking Buildbucket for the build status
[19:32:48.326] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:32:48.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:57:0) after 1m0s
[19:33:48.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:57:0)
[19:33:48.346] Timer tick, asking Buildbucket for the build status
[19:33:48.391] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:48.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:58:0) after 1m0s
[19:34:48.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:58:0)
[19:34:48.489] Timer tick, asking Buildbucket for the build status
[19:34:48.910] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:48.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:59:0) after 1m0s
[19:35:48.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:59:0)
[19:35:48.977] Timer tick, asking Buildbucket for the build status
[19:35:49.065] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:49.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:60:0) after 1m0s
[19:36:49.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:60:0)
[19:36:49.096] Timer tick, asking Buildbucket for the build status
[19:36:49.133] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:36:49.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:61:0) after 1m0s
[19:37:49.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:61:0)
[19:37:49.177] Timer tick, asking Buildbucket for the build status
[19:37:49.446] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:49.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:62:0) after 1m0s
[19:38:49.466] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:62:0)
[19:38:49.466] Timer tick, asking Buildbucket for the build status
[19:38:49.554] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:49.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:63:0) after 1m0s
[19:39:49.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:63:0)
[19:39:49.647] Timer tick, asking Buildbucket for the build status
[19:39:49.676] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:49.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:64:0) after 1m0s
[19:40:49.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:64:0)
[19:40:49.753] Timer tick, asking Buildbucket for the build status
[19:40:49.845] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:49.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:65:0) after 1m0s
[19:41:49.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:65:0)
[19:41:49.876] Timer tick, asking Buildbucket for the build status
[19:41:49.964] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:49.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:66:0) after 1m0s
[19:42:49.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:66:0)
[19:42:49.983] Timer tick, asking Buildbucket for the build status
[19:42:50.105] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:50.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:67:0) after 1m0s
[19:43:50.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:67:0)
[19:43:50.131] Timer tick, asking Buildbucket for the build status
[19:43:50.168] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:50.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:68:0) after 1m0s
[19:44:50.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:68:0)
[19:44:50.198] Timer tick, asking Buildbucket for the build status
[19:44:50.256] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:50.256] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:69:0) after 1m0s
[19:45:50.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:69:0)
[19:45:50.318] Timer tick, asking Buildbucket for the build status
[19:45:50.355] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:50.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:70:0) after 1m0s
[19:46:50.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:70:0)
[19:46:50.378] Timer tick, asking Buildbucket for the build status
[19:46:50.462] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:50.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:71:0) after 1m0s
[19:47:50.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:71:0)
[19:47:50.502] Timer tick, asking Buildbucket for the build status
[19:47:50.551] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:50.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:72:0) after 1m0s
[19:48:50.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:72:0)
[19:48:50.577] Timer tick, asking Buildbucket for the build status
[19:48:50.635] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:50.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:73:0) after 1m0s
[19:49:50.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:73:0)
[19:49:50.657] Timer tick, asking Buildbucket for the build status
[19:49:50.736] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:50.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:74:0) after 1m0s
[19:50:50.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:74:0)
[19:50:50.758] Timer tick, asking Buildbucket for the build status
[19:50:50.793] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:50.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:75:0) after 1m0s
[19:51:50.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:75:0)
[19:51:50.824] Timer tick, asking Buildbucket for the build status
[19:51:50.909] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:50.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:76:0) after 1m0s
[19:52:50.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:76:0)
[19:52:50.934] Timer tick, asking Buildbucket for the build status
[19:52:51.022] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:51.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:77:0) after 1m0s
[19:53:51.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:77:0)
[19:53:51.051] Timer tick, asking Buildbucket for the build status
[19:53:51.397] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:51.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:78:0) after 1m0s
[19:54:51.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:78:0)
[19:54:51.426] Timer tick, asking Buildbucket for the build status
[19:54:51.495] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:51.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:79:0) after 1m0s
[19:55:51.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:79:0)
[19:55:51.515] Timer tick, asking Buildbucket for the build status
[19:55:51.563] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:51.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:80:0) after 1m0s
[19:56:51.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:80:0)
[19:56:51.594] Timer tick, asking Buildbucket for the build status
[19:56:51.684] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:51.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:81:0) after 1m0s
[19:57:51.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:81:0)
[19:57:51.708] Timer tick, asking Buildbucket for the build status
[19:57:51.757] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:51.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:82:0) after 1m0s
[19:58:51.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:82:0)
[19:58:51.781] Timer tick, asking Buildbucket for the build status
[19:58:51.908] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:51.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:83:0) after 1m0s
[19:59:51.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:83:0)
[19:59:51.944] Timer tick, asking Buildbucket for the build status
[19:59:51.986] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:51.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:84:0) after 1m0s
[20:00:52.010] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:84:0)
[20:00:52.010] Timer tick, asking Buildbucket for the build status
[20:00:52.169] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:52.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:85:0) after 1m0s
[20:01:52.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:85:0)
[20:01:52.238] Timer tick, asking Buildbucket for the build status
[20:01:52.274] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:52.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:86:0) after 1m0s
[20:02:52.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:86:0)
[20:02:52.369] Timer tick, asking Buildbucket for the build status
[20:02:52.426] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:52.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:87:0) after 1m0s
[20:03:52.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:87:0)
[20:03:52.453] Timer tick, asking Buildbucket for the build status
[20:03:52.487] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:52.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:88:0) after 1m0s
[20:04:52.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:88:0)
[20:04:52.516] Timer tick, asking Buildbucket for the build status
[20:04:52.581] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:52.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:89:0) after 1m0s
[20:05:52.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:89:0)
[20:05:52.606] Timer tick, asking Buildbucket for the build status
[20:05:52.643] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:52.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:90:0) after 1m0s
[20:06:52.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:90:0)
[20:06:52.671] Timer tick, asking Buildbucket for the build status
[20:06:52.732] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:52.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:91:0) after 1m0s
[20:07:52.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:91:0)
[20:07:52.757] Timer tick, asking Buildbucket for the build status
[20:07:52.793] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:52.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:92:0) after 1m0s
[20:08:52.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:92:0)
[20:08:52.828] Timer tick, asking Buildbucket for the build status
[20:08:52.924] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:52.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:93:0) after 1m0s
[20:09:52.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:93:0)
[20:09:52.946] Timer tick, asking Buildbucket for the build status
[20:09:53.006] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:53.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:94:0) after 1m0s
[20:10:53.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:94:0)
[20:10:53.032] Timer tick, asking Buildbucket for the build status
[20:10:53.068] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:53.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:95:0) after 1m0s
[20:11:53.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:95:0)
[20:11:53.087] Timer tick, asking Buildbucket for the build status
[20:11:54.938] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:54.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:96:0) after 1m0s
[20:12:54.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:96:0)
[20:12:54.957] Timer tick, asking Buildbucket for the build status
[20:12:55.169] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:55.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:97:0) after 1m0s
[20:13:55.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:97:0)
[20:13:55.222] Timer tick, asking Buildbucket for the build status
[20:13:55.359] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:55.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:98:0) after 1m0s
[20:14:55.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:98:0)
[20:14:55.395] Timer tick, asking Buildbucket for the build status
[20:14:55.452] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:55.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:99:0) after 1m0s
[20:15:55.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:99:0)
[20:15:55.475] Timer tick, asking Buildbucket for the build status
[20:15:55.571] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:55.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:100:0) after 1m0s
[20:16:55.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:100:0)
[20:16:55.591] Timer tick, asking Buildbucket for the build status
[20:16:55.651] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:55.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:101:0) after 1m0s
[20:17:55.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:101:0)
[20:17:55.676] Timer tick, asking Buildbucket for the build status
[20:17:55.785] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:55.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:102:0) after 1m0s
[20:18:55.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:102:0)
[20:18:55.807] Timer tick, asking Buildbucket for the build status
[20:18:56.081] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:56.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:103:0) after 1m0s
[20:19:56.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:103:0)
[20:19:56.099] Timer tick, asking Buildbucket for the build status
[20:19:56.136] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:56.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:104:0) after 1m0s
[20:20:56.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:104:0)
[20:20:56.172] Timer tick, asking Buildbucket for the build status
[20:20:56.236] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:56.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:105:0) after 1m0s
[20:21:56.257] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:105:0)
[20:21:56.257] Timer tick, asking Buildbucket for the build status
[20:21:56.328] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:56.328] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:106:0) after 1m0s
[20:22:56.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:106:0)
[20:22:56.382] Timer tick, asking Buildbucket for the build status
[20:22:56.780] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:56.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:107:0) after 1m0s
[20:23:56.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:107:0)
[20:23:56.805] Timer tick, asking Buildbucket for the build status
[20:23:56.876] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:56.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:108:0) after 1m0s
[20:24:56.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:108:0)
[20:24:56.896] Timer tick, asking Buildbucket for the build status
[20:24:56.957] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:56.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:109:0) after 1m0s
[20:25:56.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:109:0)
[20:25:56.986] Timer tick, asking Buildbucket for the build status
[20:25:57.021] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:57.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:110:0) after 1m0s
[20:26:57.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:110:0)
[20:26:57.041] Timer tick, asking Buildbucket for the build status
[20:26:57.083] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:57.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:111:0) after 1m0s
[20:27:57.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:111:0)
[20:27:57.104] Timer tick, asking Buildbucket for the build status
[20:27:57.176] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:57.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:112:0) after 1m0s
[20:28:57.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:112:0)
[20:28:57.199] Timer tick, asking Buildbucket for the build status
[20:28:57.409] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:57.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:113:0) after 1m0s
[20:29:57.519] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:113:0)
[20:29:57.519] Timer tick, asking Buildbucket for the build status
[20:29:57.563] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:57.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:114:0) after 1m0s
[20:30:57.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:114:0)
[20:30:57.590] Timer tick, asking Buildbucket for the build status
[20:30:57.665] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:57.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:115:0) after 1m0s
[20:31:57.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:115:0)
[20:31:57.686] Timer tick, asking Buildbucket for the build status
[20:31:58.243] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:58.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:116:0) after 1m0s
[20:32:58.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:116:0)
[20:32:58.263] Timer tick, asking Buildbucket for the build status
[20:32:58.424] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:58.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:117:0) after 1m0s
[20:33:58.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:117:0)
[20:33:58.446] Timer tick, asking Buildbucket for the build status
[20:33:58.564] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:58.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:118:0) after 1m0s
[20:34:58.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:118:0)
[20:34:58.672] Timer tick, asking Buildbucket for the build status
[20:34:58.710] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:58.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:119:0) after 1m0s
[20:35:58.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:119:0)
[20:35:58.754] Timer tick, asking Buildbucket for the build status
[20:35:58.875] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:58.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:120:0) after 1m0s
[20:36:58.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:120:0)
[20:36:58.899] Timer tick, asking Buildbucket for the build status
[20:36:59.006] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:59.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:121:0) after 1m0s
[20:37:59.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:121:0)
[20:37:59.033] Timer tick, asking Buildbucket for the build status
[20:37:59.079] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:59.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:122:0) after 1m0s
[20:38:59.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:122:0)
[20:38:59.109] Timer tick, asking Buildbucket for the build status
[20:38:59.244] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:59.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:123:0) after 1m0s
[20:39:59.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:123:0)
[20:39:59.339] Timer tick, asking Buildbucket for the build status
[20:39:59.381] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:59.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:124:0) after 1m0s
[20:40:59.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:124:0)
[20:40:59.405] Timer tick, asking Buildbucket for the build status
[20:40:59.475] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:59.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:125:0) after 1m0s
[20:41:59.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:125:0)
[20:41:59.529] Timer tick, asking Buildbucket for the build status
[20:41:59.578] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:59.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:126:0) after 1m0s
[20:42:59.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:126:0)
[20:42:59.584] Timer tick, asking Buildbucket for the build status
[20:42:59.666] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:59.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:127:0) after 1m0s
[20:43:59.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:127:0)
[20:43:59.725] Timer tick, asking Buildbucket for the build status
[20:43:59.848] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:59.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:128:0) after 1m0s
[20:44:59.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:128:0)
[20:44:59.874] Timer tick, asking Buildbucket for the build status
[20:44:59.932] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:59.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:129:0) after 1m0s
[20:45:59.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:129:0)
[20:45:59.959] Timer tick, asking Buildbucket for the build status
[20:46:00.029] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:00.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:130:0) after 1m0s
[20:47:00.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:130:0)
[20:47:00.062] Timer tick, asking Buildbucket for the build status
[20:47:00.135] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:00.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:131:0) after 1m0s
[20:48:00.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:131:0)
[20:48:00.157] Timer tick, asking Buildbucket for the build status
[20:48:00.197] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:00.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:132:0) after 1m0s
[20:49:00.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:132:0)
[20:49:00.263] Timer tick, asking Buildbucket for the build status
[20:49:00.315] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:00.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:133:0) after 1m0s
[20:50:00.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:133:0)
[20:50:00.336] Timer tick, asking Buildbucket for the build status
[20:50:00.379] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:00.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:134:0) after 1m0s
[20:51:00.430] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:134:0)
[20:51:00.430] Timer tick, asking Buildbucket for the build status
[20:51:00.607] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:00.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:135:0) after 1m0s
[20:52:00.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:135:0)
[20:52:00.640] Timer tick, asking Buildbucket for the build status
[20:52:00.678] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:00.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:136:0) after 1m0s
[20:53:00.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:136:0)
[20:53:00.770] Timer tick, asking Buildbucket for the build status
[20:53:00.824] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:00.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:137:0) after 1m0s
[20:54:00.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:137:0)
[20:54:00.850] Timer tick, asking Buildbucket for the build status
[20:54:00.903] Build 8925237701712770224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:00.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:138:0) after 1m0s
[20:54:21.807] Received PubSub notification, asking Buildbucket for the build status
[20:54:21.858] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:00.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:138:0)
[20:55:00.925] Timer tick, asking Buildbucket for the build status
[20:55:00.990] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:00.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:140:0) after 1m0s
[20:56:01.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:140:0)
[20:56:01.019] Timer tick, asking Buildbucket for the build status
[20:56:01.099] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:01.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:141:0) after 1m0s
[20:57:01.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:141:0)
[20:57:01.124] Timer tick, asking Buildbucket for the build status
[20:57:01.247] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:01.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:142:0) after 1m0s
[20:58:01.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:142:0)
[20:58:01.271] Timer tick, asking Buildbucket for the build status
[20:58:01.391] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:01.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:143:0) after 1m0s
[20:59:01.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:143:0)
[20:59:01.412] Timer tick, asking Buildbucket for the build status
[20:59:01.791] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:01.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:144:0) after 1m0s
[21:00:01.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:144:0)
[21:00:01.816] Timer tick, asking Buildbucket for the build status
[21:00:01.888] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:01.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:145:0) after 1m0s
[21:01:01.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:145:0)
[21:01:01.916] Timer tick, asking Buildbucket for the build status
[21:01:02.002] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:02.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:146:0) after 1m0s
[21:02:02.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:146:0)
[21:02:02.023] Timer tick, asking Buildbucket for the build status
[21:02:02.278] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:02.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:147:0) after 1m0s
[21:03:02.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:147:0)
[21:03:02.301] Timer tick, asking Buildbucket for the build status
[21:03:02.340] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:02.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:148:0) after 1m0s
[21:04:02.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:148:0)
[21:04:02.362] Timer tick, asking Buildbucket for the build status
[21:04:02.457] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:02.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:149:0) after 1m0s
[21:05:02.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:149:0)
[21:05:02.489] Timer tick, asking Buildbucket for the build status
[21:05:08.575] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:08.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:150:0) after 1m0s
[21:06:08.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:150:0)
[21:06:08.599] Timer tick, asking Buildbucket for the build status
[21:06:08.654] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:08.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:151:0) after 1m0s
[21:07:08.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:151:0)
[21:07:08.681] Timer tick, asking Buildbucket for the build status
[21:07:08.727] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:08.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:152:0) after 1m0s
[21:08:08.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:152:0)
[21:08:08.759] Timer tick, asking Buildbucket for the build status
[21:08:08.819] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:153:0) after 1m0s
[21:09:08.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:153:0)
[21:09:08.861] Timer tick, asking Buildbucket for the build status
[21:09:08.931] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:08.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:154:0) after 1m0s
[21:10:08.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:154:0)
[21:10:08.951] Timer tick, asking Buildbucket for the build status
[21:10:09.122] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:09.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:155:0) after 1m0s
[21:11:09.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:155:0)
[21:11:09.143] Timer tick, asking Buildbucket for the build status
[21:11:09.213] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:09.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:156:0) after 1m0s
[21:12:09.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:156:0)
[21:12:09.277] Timer tick, asking Buildbucket for the build status
[21:12:09.341] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:09.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:157:0) after 1m0s
[21:13:09.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:157:0)
[21:13:09.362] Timer tick, asking Buildbucket for the build status
[21:13:09.501] Build 8925237701712770224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:09.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9090667764655210928:158:0) after 1m0s
[21:13:35.675] Received PubSub notification, asking Buildbucket for the build status
[21:13:35.728] Build 8925237701712770224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:13:35.728] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1546636414650396",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1546627058263990",
    "id": "8925237701712770224",
    "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\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3945-402d3be\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-x34g\"], \"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\": 4948, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_32-tests-mips\", \"got_revision\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1546627058263990, \"tags\": [\"builder:linux-pnacl-x86_32-tests-mips\", \"buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9090667764655210928\", \"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\": \"8925237701712770224\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-x34g\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1546635258927487",
    "status": "COMPLETED",
    "status_changed_ts": "1546636415254175",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4948",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9090667764655210928",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_dimension:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4948",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8925237701712770224",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
      "swarming_tag:builder:linux-pnacl-x86_32-tests-mips",
      "swarming_tag:buildset:commit/git/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/1ea07c56ac9b57c30eb784ab2af582af0cdd4b08",
      "swarming_tag:caches:builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8925237701712770224/+/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:9090667764655210928",
      "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:8df9132fd2891579a00ae465513e90e9036bee95",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4232f994e13cd810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1546636415260058",
    "url": "https://ci.chromium.org/b/8925237701712770224",
    "utcnow_ts": "1546636415715678"
  }
}
[21:13:35.728] Invocation finished in 2h35m59.654378793s with status SUCCEEDED