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

• 7f81f2db28311ca913ff7d3e4332c3d0be96a5fd (5 years ago)

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

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

[16:37:36.548] New invocation is queued and will start shortly
[16:37:38.381] Starting the invocation (attempt 1)
[16:37:38.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:37:38.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:37:38.458] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9049453694214222224",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_32-tests-mips\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\",\"title\":\"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}}",
  "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:9049453694214222224",
    "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
    "user_agent:luci-scheduler",
    "buildset:commit/git/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
    "gitiles_ref:refs/heads/master"
  ]
}
[16:37:39.361] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585931858577604",
    "id": "8884023631418560016",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_32-tests-mips\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"title\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585931859092140",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4995",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9049453694214222224",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884023631418560016/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585931859092271",
    "url": "https://ci.chromium.org/b/8884023631418560016",
    "utcnow_ts": "1585931859352417"
  }
}
[16:37:39.361] Task URL: https://ci.chromium.org/b/8884023631418560016
[16:37:39.361] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:2:0) after 1m0s
[16:38:39.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:2:0)
[16:38:39.494] Timer tick, asking Buildbucket for the build status
[16:38:39.828] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:39.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:3:0) after 1m0s
[16:39:40.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:3:0)
[16:39:40.005] Timer tick, asking Buildbucket for the build status
[16:39:40.171] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:40.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:4:0) after 1m0s
[16:40:40.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:4:0)
[16:40:40.313] Timer tick, asking Buildbucket for the build status
[16:40:40.447] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:40.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:5:0) after 1m0s
[16:41:40.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:5:0)
[16:41:40.591] Timer tick, asking Buildbucket for the build status
[16:41:40.755] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:40.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:6:0) after 1m0s
[16:42:40.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:6:0)
[16:42:40.805] Timer tick, asking Buildbucket for the build status
[16:42:40.962] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:40.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:7:0) after 1m0s
[16:43:41.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:7:0)
[16:43:41.093] Timer tick, asking Buildbucket for the build status
[16:43:41.382] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:43:41.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:8:0) after 1m0s
[16:44:41.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:8:0)
[16:44:41.644] Timer tick, asking Buildbucket for the build status
[16:44:41.743] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:41.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:9:0) after 1m0s
[16:45:41.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:9:0)
[16:45:41.771] Timer tick, asking Buildbucket for the build status
[16:45:42.286] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:42.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:10:0) after 1m0s
[16:46:42.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:10:0)
[16:46:42.404] Timer tick, asking Buildbucket for the build status
[16:46:42.539] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:42.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:11:0) after 1m0s
[16:47:42.723] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:11:0)
[16:47:42.723] Timer tick, asking Buildbucket for the build status
[16:47:42.994] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:42.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:12:0) after 1m0s
[16:48:43.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:12:0)
[16:48:43.049] Timer tick, asking Buildbucket for the build status
[16:48:43.218] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:43.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:13:0) after 1m0s
[16:49:43.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:13:0)
[16:49:43.394] Timer tick, asking Buildbucket for the build status
[16:49:43.499] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:43.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:14:0) after 1m0s
[16:50:43.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:14:0)
[16:50:43.767] Timer tick, asking Buildbucket for the build status
[16:50:44.315] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:50:44.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:15:0) after 1m0s
[16:51:44.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:15:0)
[16:51:44.319] Timer tick, asking Buildbucket for the build status
[16:51:44.453] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:51:44.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:16:0) after 1m0s
[16:52:44.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:16:0)
[16:52:44.522] Timer tick, asking Buildbucket for the build status
[16:52:44.661] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:44.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:17:0) after 1m0s
[16:53:44.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:17:0)
[16:53:44.638] Timer tick, asking Buildbucket for the build status
[16:53:44.899] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:44.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:18:0) after 1m0s
[16:54:44.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:18:0)
[16:54:44.905] Timer tick, asking Buildbucket for the build status
[16:54:45.038] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:45.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:19:0) after 1m0s
[16:55:45.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:19:0)
[16:55:45.089] Timer tick, asking Buildbucket for the build status
[16:55:45.257] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:45.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:20:0) after 1m0s
[16:56:45.323] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:20:0)
[16:56:45.323] Timer tick, asking Buildbucket for the build status
[16:56:45.508] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:45.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:21:0) after 1m0s
[16:57:45.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:21:0)
[16:57:45.985] Timer tick, asking Buildbucket for the build status
[16:57:46.360] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:46.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:22:0) after 1m0s
[16:58:46.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:22:0)
[16:58:46.525] Timer tick, asking Buildbucket for the build status
[16:58:50.442] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:58:50.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:23:0) after 1m0s
[16:59:50.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:23:0)
[16:59:50.479] Timer tick, asking Buildbucket for the build status
[16:59:50.618] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:50.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:24:0) after 1m0s
[17:00:50.630] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:24:0)
[17:00:50.630] Timer tick, asking Buildbucket for the build status
[17:00:50.783] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:00:50.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:25:0) after 1m0s
[17:01:50.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:25:0)
[17:01:50.803] Timer tick, asking Buildbucket for the build status
[17:01:50.945] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:01:50.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:26:0) after 1m0s
[17:02:50.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:26:0)
[17:02:50.980] Timer tick, asking Buildbucket for the build status
[17:02:51.177] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:02:51.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:27:0) after 1m0s
[17:03:51.297] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:27:0)
[17:03:51.297] Timer tick, asking Buildbucket for the build status
[17:03:51.600] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:03:51.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:28:0) after 1m0s
[17:04:51.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:28:0)
[17:04:51.698] Timer tick, asking Buildbucket for the build status
[17:04:51.901] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:04:51.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:29:0) after 1m0s
[17:05:51.951] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:29:0)
[17:05:51.951] Timer tick, asking Buildbucket for the build status
[17:05:52.485] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:05:52.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:30:0) after 1m0s
[17:06:52.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:30:0)
[17:06:52.537] Timer tick, asking Buildbucket for the build status
[17:06:52.698] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:06:52.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:31:0) after 1m0s
[17:07:52.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:31:0)
[17:07:52.783] Timer tick, asking Buildbucket for the build status
[17:07:52.910] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:07:52.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:32:0) after 1m0s
[17:08:53.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:32:0)
[17:08:53.102] Timer tick, asking Buildbucket for the build status
[17:08:53.248] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:08:53.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:33:0) after 1m0s
[17:09:53.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:33:0)
[17:09:53.362] Timer tick, asking Buildbucket for the build status
[17:09:53.776] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:09:53.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:34:0) after 1m0s
[17:10:53.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:34:0)
[17:10:53.876] Timer tick, asking Buildbucket for the build status
[17:10:54.038] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:10:54.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:35:0) after 1m0s
[17:11:54.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:35:0)
[17:11:54.086] Timer tick, asking Buildbucket for the build status
[17:11:55.086] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:11:55.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:36:0) after 1m0s
[17:12:55.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:36:0)
[17:12:55.234] Timer tick, asking Buildbucket for the build status
[17:12:55.487] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:12:55.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:37:0) after 1m0s
[17:13:55.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:37:0)
[17:13:55.532] Timer tick, asking Buildbucket for the build status
[17:13:55.717] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:13:55.717] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:38:0) after 1m0s
[17:14:55.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:38:0)
[17:14:55.781] Timer tick, asking Buildbucket for the build status
[17:14:56.071] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:14:56.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:39:0) after 1m0s
[17:15:56.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:39:0)
[17:15:56.093] Timer tick, asking Buildbucket for the build status
[17:15:56.292] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:15:56.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:40:0) after 1m0s
[17:16:56.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:40:0)
[17:16:56.317] Timer tick, asking Buildbucket for the build status
[17:16:56.553] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:16:56.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:41:0) after 1m0s
[17:17:56.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:41:0)
[17:17:56.607] Timer tick, asking Buildbucket for the build status
[17:17:56.780] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:17:56.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:42:0) after 1m0s
[17:18:56.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:42:0)
[17:18:56.877] Timer tick, asking Buildbucket for the build status
[17:18:57.055] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:18:57.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:43:0) after 1m0s
[17:19:57.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:43:0)
[17:19:57.132] Timer tick, asking Buildbucket for the build status
[17:19:57.416] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:19:57.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:44:0) after 1m0s
[17:20:57.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:44:0)
[17:20:57.469] Timer tick, asking Buildbucket for the build status
[17:20:57.595] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:20:57.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:45:0) after 1m0s
[17:21:57.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:45:0)
[17:21:57.690] Timer tick, asking Buildbucket for the build status
[17:21:58.109] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:58.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:46:0) after 1m0s
[17:22:58.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:46:0)
[17:22:58.194] Timer tick, asking Buildbucket for the build status
[17:22:58.604] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:58.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:47:0) after 1m0s
[17:23:58.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:47:0)
[17:23:58.636] Timer tick, asking Buildbucket for the build status
[17:23:58.772] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:58.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:48:0) after 1m0s
[17:24:58.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:48:0)
[17:24:58.802] Timer tick, asking Buildbucket for the build status
[17:24:58.947] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:58.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:49:0) after 1m0s
[17:25:58.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:49:0)
[17:25:58.974] Timer tick, asking Buildbucket for the build status
[17:25:59.119] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:59.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:50:0) after 1m0s
[17:26:59.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:50:0)
[17:26:59.169] Timer tick, asking Buildbucket for the build status
[17:26:59.565] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:59.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:51:0) after 1m0s
[17:27:59.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:51:0)
[17:27:59.650] Timer tick, asking Buildbucket for the build status
[17:27:59.852] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:27:59.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:52:0) after 1m0s
[17:29:00.193] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:52:0)
[17:29:00.193] Timer tick, asking Buildbucket for the build status
[17:29:00.385] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:29:00.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:53:0) after 1m0s
[17:30:00.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:53:0)
[17:30:00.644] Timer tick, asking Buildbucket for the build status
[17:30:00.936] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:00.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:54:0) after 1m0s
[17:31:01.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:54:0)
[17:31:01.305] Timer tick, asking Buildbucket for the build status
[17:31:01.595] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:01.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:55:0) after 1m0s
[17:32:01.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:55:0)
[17:32:01.666] Timer tick, asking Buildbucket for the build status
[17:32:01.949] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:01.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:56:0) after 1m0s
[17:33:02.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:56:0)
[17:33:02.212] Timer tick, asking Buildbucket for the build status
[17:33:02.456] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:02.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:57:0) after 1m0s
[17:34:02.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:57:0)
[17:34:02.497] Timer tick, asking Buildbucket for the build status
[17:34:02.610] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:02.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:58:0) after 1m0s
[17:35:02.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:58:0)
[17:35:02.748] Timer tick, asking Buildbucket for the build status
[17:35:02.872] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:02.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:59:0) after 1m0s
[17:36:03.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:59:0)
[17:36:03.020] Timer tick, asking Buildbucket for the build status
[17:36:03.217] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:03.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:60:0) after 1m0s
[17:37:03.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:60:0)
[17:37:03.307] Timer tick, asking Buildbucket for the build status
[17:37:03.625] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:03.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:61:0) after 1m0s
[17:38:03.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:61:0)
[17:38:03.733] Timer tick, asking Buildbucket for the build status
[17:38:04.103] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:04.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:62:0) after 1m0s
[17:39:04.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:62:0)
[17:39:04.194] Timer tick, asking Buildbucket for the build status
[17:39:04.314] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:04.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:63:0) after 1m0s
[17:40:04.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:63:0)
[17:40:04.375] Timer tick, asking Buildbucket for the build status
[17:40:04.521] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:04.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:64:0) after 1m0s
[17:41:04.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:64:0)
[17:41:04.666] Timer tick, asking Buildbucket for the build status
[17:41:04.809] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:04.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:65:0) after 1m0s
[17:42:04.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:65:0)
[17:42:04.870] Timer tick, asking Buildbucket for the build status
[17:42:05.464] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:05.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:66:0) after 1m0s
[17:43:05.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:66:0)
[17:43:05.538] Timer tick, asking Buildbucket for the build status
[17:43:05.845] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:05.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:67:0) after 1m0s
[17:44:05.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:67:0)
[17:44:05.882] Timer tick, asking Buildbucket for the build status
[17:44:06.034] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:44:06.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:68:0) after 1m0s
[17:45:06.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:68:0)
[17:45:06.091] Timer tick, asking Buildbucket for the build status
[17:45:06.268] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:06.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:69:0) after 1m0s
[17:46:06.303] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:69:0)
[17:46:06.303] Timer tick, asking Buildbucket for the build status
[17:46:06.773] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:06.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:70:0) after 1m0s
[17:47:06.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:70:0)
[17:47:06.827] Timer tick, asking Buildbucket for the build status
[17:47:07.135] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:07.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:71:0) after 1m0s
[17:48:07.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:71:0)
[17:48:07.167] Timer tick, asking Buildbucket for the build status
[17:48:07.276] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:07.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:72:0) after 1m0s
[17:49:07.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:72:0)
[17:49:07.322] Timer tick, asking Buildbucket for the build status
[17:49:07.440] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:07.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:73:0) after 1m0s
[17:50:07.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:73:0)
[17:50:07.513] Timer tick, asking Buildbucket for the build status
[17:50:07.888] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:07.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:74:0) after 1m0s
[17:51:07.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:74:0)
[17:51:07.916] Timer tick, asking Buildbucket for the build status
[17:51:08.125] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:51:08.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:75:0) after 1m0s
[17:52:08.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:75:0)
[17:52:08.137] Timer tick, asking Buildbucket for the build status
[17:52:08.263] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:52:08.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:76:0) after 1m0s
[17:53:08.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:76:0)
[17:53:08.346] Timer tick, asking Buildbucket for the build status
[17:53:08.954] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:08.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:77:0) after 1m0s
[17:54:08.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:77:0)
[17:54:08.995] Timer tick, asking Buildbucket for the build status
[17:54:09.226] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:09.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:78:0) after 1m0s
[17:55:09.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:78:0)
[17:55:09.264] Timer tick, asking Buildbucket for the build status
[17:55:10.200] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:10.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:79:0) after 1m0s
[17:56:10.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:79:0)
[17:56:10.328] Timer tick, asking Buildbucket for the build status
[17:56:10.440] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:10.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:80:0) after 1m0s
[17:57:10.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:80:0)
[17:57:10.502] Timer tick, asking Buildbucket for the build status
[17:57:11.013] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:11.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:81:0) after 1m0s
[17:58:11.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:81:0)
[17:58:11.205] Timer tick, asking Buildbucket for the build status
[17:58:11.343] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:58:11.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:82:0) after 1m0s
[17:59:11.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:82:0)
[17:59:11.419] Timer tick, asking Buildbucket for the build status
[17:59:11.996] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:11.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:83:0) after 1m0s
[18:00:12.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:83:0)
[18:00:12.041] Timer tick, asking Buildbucket for the build status
[18:00:12.354] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:12.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:84:0) after 1m0s
[18:01:12.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:84:0)
[18:01:12.517] Timer tick, asking Buildbucket for the build status
[18:01:12.619] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:12.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:85:0) after 1m0s
[18:02:12.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:85:0)
[18:02:12.678] Timer tick, asking Buildbucket for the build status
[18:02:18.873] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:18.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:86:0) after 1m0s
[18:03:18.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:86:0)
[18:03:18.950] Timer tick, asking Buildbucket for the build status
[18:03:19.985] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:19.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:87:0) after 1m0s
[18:04:20.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:87:0)
[18:04:20.053] Timer tick, asking Buildbucket for the build status
[18:04:20.505] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:20.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:88:0) after 1m0s
[18:05:20.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:88:0)
[18:05:20.605] Timer tick, asking Buildbucket for the build status
[18:05:21.208] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:21.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:89:0) after 1m0s
[18:06:21.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:89:0)
[18:06:21.268] Timer tick, asking Buildbucket for the build status
[18:06:21.947] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:21.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:90:0) after 1m0s
[18:07:22.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:90:0)
[18:07:22.039] Timer tick, asking Buildbucket for the build status
[18:07:22.275] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:22.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:91:0) after 1m0s
[18:08:22.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:91:0)
[18:08:22.338] Timer tick, asking Buildbucket for the build status
[18:08:22.519] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:22.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:92:0) after 1m0s
[18:09:22.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:92:0)
[18:09:22.525] Timer tick, asking Buildbucket for the build status
[18:09:23.556] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:23.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:93:0) after 1m0s
[18:10:23.629] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:93:0)
[18:10:23.629] Timer tick, asking Buildbucket for the build status
[18:10:23.843] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:23.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:94:0) after 1m0s
[18:11:23.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:94:0)
[18:11:23.889] Timer tick, asking Buildbucket for the build status
[18:11:24.108] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:24.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:95:0) after 1m0s
[18:12:24.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:95:0)
[18:12:24.149] Timer tick, asking Buildbucket for the build status
[18:12:24.279] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:24.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:96:0) after 1m0s
[18:13:24.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:96:0)
[18:13:24.369] Timer tick, asking Buildbucket for the build status
[18:13:24.854] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:24.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:97:0) after 1m0s
[18:14:24.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:97:0)
[18:14:24.912] Timer tick, asking Buildbucket for the build status
[18:14:25.034] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:25.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:98:0) after 1m0s
[18:15:25.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:98:0)
[18:15:25.055] Timer tick, asking Buildbucket for the build status
[18:15:26.217] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:15:26.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:99:0) after 1m0s
[18:16:26.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:99:0)
[18:16:26.314] Timer tick, asking Buildbucket for the build status
[18:16:26.482] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:16:26.482] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:100:0) after 1m0s
[18:17:26.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:100:0)
[18:17:26.541] Timer tick, asking Buildbucket for the build status
[18:17:27.062] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:27.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:101:0) after 1m0s
[18:18:27.088] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:101:0)
[18:18:27.088] Timer tick, asking Buildbucket for the build status
[18:18:27.185] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:27.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:102:0) after 1m0s
[18:19:27.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:102:0)
[18:19:27.509] Timer tick, asking Buildbucket for the build status
[18:19:28.000] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:28.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:103:0) after 1m0s
[18:20:28.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:103:0)
[18:20:28.221] Timer tick, asking Buildbucket for the build status
[18:20:28.324] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:28.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:104:0) after 1m0s
[18:21:28.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:104:0)
[18:21:28.522] Timer tick, asking Buildbucket for the build status
[18:21:28.642] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:28.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:105:0) after 1m0s
[18:22:28.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:105:0)
[18:22:28.772] Timer tick, asking Buildbucket for the build status
[18:22:29.180] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:29.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:106:0) after 1m0s
[18:23:29.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:106:0)
[18:23:29.269] Timer tick, asking Buildbucket for the build status
[18:23:29.409] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:29.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:107:0) after 1m0s
[18:24:29.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:107:0)
[18:24:29.484] Timer tick, asking Buildbucket for the build status
[18:24:29.957] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:29.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:108:0) after 1m0s
[18:25:30.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:108:0)
[18:25:30.185] Timer tick, asking Buildbucket for the build status
[18:25:30.537] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:30.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:109:0) after 1m0s
[18:26:30.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:109:0)
[18:26:30.582] Timer tick, asking Buildbucket for the build status
[18:26:30.706] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:30.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:110:0) after 1m0s
[18:27:30.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:110:0)
[18:27:30.816] Timer tick, asking Buildbucket for the build status
[18:27:30.964] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:30.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:111:0) after 1m0s
[18:28:31.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:111:0)
[18:28:31.058] Timer tick, asking Buildbucket for the build status
[18:28:31.200] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:31.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:112:0) after 1m0s
[18:29:31.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:112:0)
[18:29:31.220] Timer tick, asking Buildbucket for the build status
[18:29:31.356] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:31.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:113:0) after 1m0s
[18:30:31.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:113:0)
[18:30:31.388] Timer tick, asking Buildbucket for the build status
[18:30:31.859] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:30:31.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:114:0) after 1m0s
[18:31:31.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:114:0)
[18:31:31.995] Timer tick, asking Buildbucket for the build status
[18:31:32.151] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:32.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:115:0) after 1m0s
[18:32:32.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:115:0)
[18:32:32.288] Timer tick, asking Buildbucket for the build status
[18:32:32.551] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:32.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:116:0) after 1m0s
[18:33:32.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:116:0)
[18:33:32.684] Timer tick, asking Buildbucket for the build status
[18:33:33.257] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:33.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:117:0) after 1m0s
[18:34:33.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:117:0)
[18:34:33.599] Timer tick, asking Buildbucket for the build status
[18:34:33.777] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:33.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:118:0) after 1m0s
[18:35:34.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:118:0)
[18:35:34.058] Timer tick, asking Buildbucket for the build status
[18:35:34.468] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:34.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:119:0) after 1m0s
[18:36:34.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:119:0)
[18:36:34.698] Timer tick, asking Buildbucket for the build status
[18:36:35.488] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:36:35.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:120:0) after 1m0s
[18:37:36.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:120:0)
[18:37:36.106] Timer tick, asking Buildbucket for the build status
[18:37:36.466] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:37:36.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:121:0) after 1m0s
[18:38:36.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:121:0)
[18:38:36.522] Timer tick, asking Buildbucket for the build status
[18:38:36.854] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:38:36.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:122:0) after 1m0s
[18:39:36.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:122:0)
[18:39:36.981] Timer tick, asking Buildbucket for the build status
[18:39:37.133] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:39:37.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:123:0) after 1m0s
[18:40:37.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:123:0)
[18:40:37.150] Timer tick, asking Buildbucket for the build status
[18:40:37.538] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:40:37.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:124:0) after 1m0s
[18:41:37.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:124:0)
[18:41:37.598] Timer tick, asking Buildbucket for the build status
[18:41:37.807] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:41:37.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:125:0) after 1m0s
[18:42:37.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:125:0)
[18:42:37.900] Timer tick, asking Buildbucket for the build status
[18:42:38.347] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:42:38.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:126:0) after 1m0s
[18:43:38.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:126:0)
[18:43:38.385] Timer tick, asking Buildbucket for the build status
[18:43:38.485] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:43:38.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:127:0) after 1m0s
[18:44:38.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:127:0)
[18:44:38.613] Timer tick, asking Buildbucket for the build status
[18:44:38.907] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:38.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:128:0) after 1m0s
[18:45:38.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:128:0)
[18:45:38.977] Timer tick, asking Buildbucket for the build status
[18:45:39.152] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:39.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:129:0) after 1m0s
[18:46:39.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:129:0)
[18:46:39.232] Timer tick, asking Buildbucket for the build status
[18:46:39.781] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:39.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:130:0) after 1m0s
[18:47:39.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:130:0)
[18:47:39.881] Timer tick, asking Buildbucket for the build status
[18:47:40.146] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:40.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:131:0) after 1m0s
[18:48:40.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:131:0)
[18:48:40.224] Timer tick, asking Buildbucket for the build status
[18:48:40.342] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:48:40.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:132:0) after 1m0s
[18:49:40.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:132:0)
[18:49:40.434] Timer tick, asking Buildbucket for the build status
[18:49:40.879] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:49:40.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:133:0) after 1m0s
[18:50:40.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:133:0)
[18:50:40.958] Timer tick, asking Buildbucket for the build status
[18:50:41.088] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:50:41.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:134:0) after 1m0s
[18:51:41.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:134:0)
[18:51:41.268] Timer tick, asking Buildbucket for the build status
[18:51:41.393] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:51:41.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:135:0) after 1m0s
[18:52:41.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:135:0)
[18:52:41.478] Timer tick, asking Buildbucket for the build status
[18:52:41.623] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:52:41.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:136:0) after 1m0s
[18:53:41.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:136:0)
[18:53:41.618] Timer tick, asking Buildbucket for the build status
[18:53:41.778] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:41.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:137:0) after 1m0s
[18:54:41.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:137:0)
[18:54:41.834] Timer tick, asking Buildbucket for the build status
[18:54:42.059] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:42.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:138:0) after 1m0s
[18:55:42.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:138:0)
[18:55:42.125] Timer tick, asking Buildbucket for the build status
[18:55:42.556] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:42.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:139:0) after 1m0s
[18:56:42.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:139:0)
[18:56:42.705] Timer tick, asking Buildbucket for the build status
[18:56:42.921] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:42.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:140:0) after 1m0s
[18:57:42.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:140:0)
[18:57:42.934] Timer tick, asking Buildbucket for the build status
[18:57:43.105] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:43.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:141:0) after 1m0s
[18:58:43.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:141:0)
[18:58:43.192] Timer tick, asking Buildbucket for the build status
[18:58:43.327] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:43.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:142:0) after 1m0s
[18:59:43.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:142:0)
[18:59:43.406] Timer tick, asking Buildbucket for the build status
[18:59:43.883] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:43.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:143:0) after 1m0s
[19:00:44.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:143:0)
[19:00:44.023] Timer tick, asking Buildbucket for the build status
[19:00:44.139] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:44.139] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:144:0) after 1m0s
[19:01:44.181] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:144:0)
[19:01:44.181] Timer tick, asking Buildbucket for the build status
[19:01:44.341] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:44.341] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:145:0) after 1m0s
[19:02:44.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:145:0)
[19:02:44.491] Timer tick, asking Buildbucket for the build status
[19:02:44.645] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:44.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:146:0) after 1m0s
[19:03:44.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:146:0)
[19:03:44.764] Timer tick, asking Buildbucket for the build status
[19:03:44.894] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:44.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:147:0) after 1m0s
[19:04:44.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:147:0)
[19:04:44.944] Timer tick, asking Buildbucket for the build status
[19:04:45.122] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:45.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:148:0) after 1m0s
[19:05:45.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:148:0)
[19:05:45.178] Timer tick, asking Buildbucket for the build status
[19:05:45.356] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:45.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:149:0) after 1m0s
[19:06:45.521] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:149:0)
[19:06:45.521] Timer tick, asking Buildbucket for the build status
[19:06:45.676] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:45.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:150:0) after 1m0s
[19:07:45.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:150:0)
[19:07:45.794] Timer tick, asking Buildbucket for the build status
[19:07:45.945] Build 8884023631418560016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:45.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:151:0) after 1m0s
[19:07:53.832] Received PubSub notification, asking Buildbucket for the build status
[19:07:53.918] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:46.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:151:0)
[19:08:46.046] Timer tick, asking Buildbucket for the build status
[19:08:46.676] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:46.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:153:0) after 1m0s
[19:09:46.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:153:0)
[19:09:46.772] Timer tick, asking Buildbucket for the build status
[19:09:46.907] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:46.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:154:0) after 1m0s
[19:10:47.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:154:0)
[19:10:47.084] Timer tick, asking Buildbucket for the build status
[19:10:57.855] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:57.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:155:0) after 1m0s
[19:11:57.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:155:0)
[19:11:57.947] Timer tick, asking Buildbucket for the build status
[19:11:58.158] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:58.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:156:0) after 1m0s
[19:12:58.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:156:0)
[19:12:58.253] Timer tick, asking Buildbucket for the build status
[19:12:58.391] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:58.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:157:0) after 1m0s
[19:13:58.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:157:0)
[19:13:58.436] Timer tick, asking Buildbucket for the build status
[19:13:58.607] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:58.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:158:0) after 1m0s
[19:14:58.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:158:0)
[19:14:58.737] Timer tick, asking Buildbucket for the build status
[19:14:58.986] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:58.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:159:0) after 1m0s
[19:15:59.077] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:159:0)
[19:15:59.077] Timer tick, asking Buildbucket for the build status
[19:15:59.226] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:59.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:160:0) after 1m0s
[19:16:59.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:160:0)
[19:16:59.365] Timer tick, asking Buildbucket for the build status
[19:16:59.698] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:59.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:161:0) after 1m0s
[19:17:59.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:161:0)
[19:17:59.845] Timer tick, asking Buildbucket for the build status
[19:18:00.096] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:00.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:162:0) after 1m0s
[19:19:00.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:162:0)
[19:19:00.133] Timer tick, asking Buildbucket for the build status
[19:19:00.418] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:00.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:163:0) after 1m0s
[19:20:00.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:163:0)
[19:20:00.495] Timer tick, asking Buildbucket for the build status
[19:20:00.661] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:00.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:164:0) after 1m0s
[19:21:00.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:164:0)
[19:21:00.758] Timer tick, asking Buildbucket for the build status
[19:21:00.866] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:00.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:165:0) after 1m0s
[19:22:00.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:165:0)
[19:22:00.902] Timer tick, asking Buildbucket for the build status
[19:22:01.345] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:01.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:166:0) after 1m0s
[19:23:01.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:166:0)
[19:23:01.403] Timer tick, asking Buildbucket for the build status
[19:23:01.534] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:01.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:167:0) after 1m0s
[19:24:01.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:167:0)
[19:24:01.695] Timer tick, asking Buildbucket for the build status
[19:24:01.905] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:01.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:168:0) after 1m0s
[19:25:01.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:168:0)
[19:25:01.997] Timer tick, asking Buildbucket for the build status
[19:25:02.350] Build 8884023631418560016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:02.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9049453694214222224:169:0) after 1m0s
[19:25:29.348] Received PubSub notification, asking Buildbucket for the build status
[19:25:29.591] Build 8884023631418560016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:25:29.591] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1585941928308907",
    "created_by": "project:nacl",
    "created_ts": "1585931858577604",
    "id": "8884023631418560016",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_32-tests-mips\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"title\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"title\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-u8o4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_32-tests-mips\", \"buildnumber\": 4995, \"got_revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7f81f2db28311ca913ff7d3e4332c3d0be96a5fd\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-u8o4\"], \"image\": [\"chrome-xenial-20032900-6802ab9ce34\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4926-84b94b1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585940872440674",
    "status": "COMPLETED",
    "status_changed_ts": "1585941928755922",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4995",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7f81f2db28311ca913ff7d3e4332c3d0be96a5fd",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9049453694214222224",
      "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884023631418560016/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:4b59b93ed2a5f810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585941928769574",
    "url": "https://ci.chromium.org/b/8884023631418560016",
    "utcnow_ts": "1585941929577239"
  }
}
[19:25:29.591] Invocation finished in 2h47m53.063043636s with status SUCCEEDED