Status: SUCCEEDED
Triggered by: -
Duration: 2 hours
Actions:

Definition (rev 1c82d86159c2071b02a7ddf08ffd2ae0bff92186)

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

Incoming triggers

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[03:07:20.771] New invocation is queued and will start shortly
[03:07:22.885] Starting the invocation (attempt 1)
[03:07:22.950] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:07:22.950] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:07:22.950] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9052766162336806176",
  "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\":\"d3db562593968b5b8761117d31f96eae305c37a7\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d3db562593968b5b8761117d31f96eae305c37a7\",\"title\":\"d3db562593968b5b8761117d31f96eae305c37a7\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/d3db562593968b5b8761117d31f96eae305c37a7\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"d3db562593968b5b8761117d31f96eae305c37a7\"}}",
  "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:9052766162336806176",
    "scheduler_job_id:nacl/linux-pnacl-x86_32-tests-mips",
    "user_agent:luci-scheduler",
    "buildset:commit/git/d3db562593968b5b8761117d31f96eae305c37a7",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d3db562593968b5b8761117d31f96eae305c37a7",
    "gitiles_ref:refs/heads/master"
  ]
}
[03:07:23.860] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:nacl",
    "created_ts": "1582772843149583",
    "id": "8887336099180903520",
    "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\": \"d3db562593968b5b8761117d31f96eae305c37a7\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d3db562593968b5b8761117d31f96eae305c37a7\", \"title\": \"d3db562593968b5b8761117d31f96eae305c37a7\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d3db562593968b5b8761117d31f96eae305c37a7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d3db562593968b5b8761117d31f96eae305c37a7\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1582772843481383",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4978",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/d3db562593968b5b8761117d31f96eae305c37a7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d3db562593968b5b8761117d31f96eae305c37a7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052766162336806176",
      "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/8887336099180903520/+/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": "1582772843481479",
    "url": "https://ci.chromium.org/b/8887336099180903520",
    "utcnow_ts": "1582772843847347"
  }
}
[03:07:23.860] Task URL: https://ci.chromium.org/b/8887336099180903520
[03:07:23.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:2:0) after 1m0s
[03:08:23.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:2:0)
[03:08:23.963] Timer tick, asking Buildbucket for the build status
[03:08:24.127] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:24.127] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:3:0) after 1m0s
[03:09:24.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:3:0)
[03:09:24.182] Timer tick, asking Buildbucket for the build status
[03:09:24.325] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:24.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:4:0) after 1m0s
[03:10:24.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:4:0)
[03:10:24.343] Timer tick, asking Buildbucket for the build status
[03:10:24.452] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:24.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:5:0) after 1m0s
[03:11:24.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:5:0)
[03:11:24.471] Timer tick, asking Buildbucket for the build status
[03:11:25.039] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:25.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:6:0) after 1m0s
[03:12:25.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:6:0)
[03:12:25.116] Timer tick, asking Buildbucket for the build status
[03:12:25.230] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:25.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:7:0) after 1m0s
[03:13:25.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:7:0)
[03:13:25.309] Timer tick, asking Buildbucket for the build status
[03:13:25.415] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:25.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:8:0) after 1m0s
[03:14:25.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:8:0)
[03:14:25.472] Timer tick, asking Buildbucket for the build status
[03:14:25.801] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:25.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:9:0) after 1m0s
[03:15:25.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:9:0)
[03:15:25.846] Timer tick, asking Buildbucket for the build status
[03:15:25.990] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:25.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:10:0) after 1m0s
[03:16:26.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:10:0)
[03:16:26.046] Timer tick, asking Buildbucket for the build status
[03:16:26.164] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:26.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:11:0) after 1m0s
[03:17:26.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:11:0)
[03:17:26.265] Timer tick, asking Buildbucket for the build status
[03:17:26.586] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:26.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:12:0) after 1m0s
[03:18:26.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:12:0)
[03:18:26.646] Timer tick, asking Buildbucket for the build status
[03:18:26.833] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:26.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:13:0) after 1m0s
[03:19:26.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:13:0)
[03:19:26.895] Timer tick, asking Buildbucket for the build status
[03:19:27.010] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:27.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:14:0) after 1m0s
[03:20:27.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:14:0)
[03:20:27.048] Timer tick, asking Buildbucket for the build status
[03:20:27.213] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:27.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:15:0) after 1m0s
[03:21:27.239] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:15:0)
[03:21:27.239] Timer tick, asking Buildbucket for the build status
[03:21:27.384] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:27.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:16:0) after 1m0s
[03:22:27.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:16:0)
[03:22:27.411] Timer tick, asking Buildbucket for the build status
[03:22:27.516] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:27.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:17:0) after 1m0s
[03:23:27.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:17:0)
[03:23:27.535] Timer tick, asking Buildbucket for the build status
[03:23:27.696] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:27.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:18:0) after 1m0s
[03:24:27.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:18:0)
[03:24:27.742] Timer tick, asking Buildbucket for the build status
[03:24:27.928] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:27.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:19:0) after 1m0s
[03:25:27.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:19:0)
[03:25:27.982] Timer tick, asking Buildbucket for the build status
[03:25:28.259] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:28.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:20:0) after 1m0s
[03:26:28.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:20:0)
[03:26:28.292] Timer tick, asking Buildbucket for the build status
[03:26:28.496] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:28.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:21:0) after 1m0s
[03:27:28.586] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:21:0)
[03:27:28.586] Timer tick, asking Buildbucket for the build status
[03:27:29.006] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:29.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:22:0) after 1m0s
[03:28:29.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:22:0)
[03:28:29.035] Timer tick, asking Buildbucket for the build status
[03:28:29.226] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:29.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:23:0) after 1m0s
[03:29:29.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:23:0)
[03:29:29.306] Timer tick, asking Buildbucket for the build status
[03:29:29.534] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:29.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:24:0) after 1m0s
[03:30:29.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:24:0)
[03:30:29.626] Timer tick, asking Buildbucket for the build status
[03:30:29.739] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:29.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:25:0) after 1m0s
[03:31:29.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:25:0)
[03:31:29.824] Timer tick, asking Buildbucket for the build status
[03:31:30.050] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:30.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:26:0) after 1m0s
[03:32:30.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:26:0)
[03:32:30.138] Timer tick, asking Buildbucket for the build status
[03:32:30.257] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:30.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:27:0) after 1m0s
[03:33:30.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:27:0)
[03:33:30.230] Timer tick, asking Buildbucket for the build status
[03:33:30.334] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:30.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:28:0) after 1m0s
[03:34:30.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:28:0)
[03:34:30.387] Timer tick, asking Buildbucket for the build status
[03:34:30.635] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:30.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:29:0) after 1m0s
[03:35:30.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:29:0)
[03:35:30.727] Timer tick, asking Buildbucket for the build status
[03:35:30.925] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:30.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:30:0) after 1m0s
[03:36:30.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:30:0)
[03:36:30.958] Timer tick, asking Buildbucket for the build status
[03:36:31.084] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:31.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:31:0) after 1m0s
[03:37:31.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:31:0)
[03:37:31.125] Timer tick, asking Buildbucket for the build status
[03:37:31.524] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:31.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:32:0) after 1m0s
[03:38:31.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:32:0)
[03:38:31.592] Timer tick, asking Buildbucket for the build status
[03:38:31.681] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:31.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:33:0) after 1m0s
[03:39:31.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:33:0)
[03:39:31.751] Timer tick, asking Buildbucket for the build status
[03:39:31.929] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:31.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:34:0) after 1m0s
[03:40:31.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:34:0)
[03:40:31.985] Timer tick, asking Buildbucket for the build status
[03:40:32.124] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:32.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:35:0) after 1m0s
[03:41:32.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:35:0)
[03:41:32.153] Timer tick, asking Buildbucket for the build status
[03:41:32.373] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:32.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:36:0) after 1m0s
[03:42:32.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:36:0)
[03:42:32.405] Timer tick, asking Buildbucket for the build status
[03:42:32.592] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:32.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:37:0) after 1m0s
[03:43:32.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:37:0)
[03:43:32.657] Timer tick, asking Buildbucket for the build status
[03:43:32.968] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:32.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:38:0) after 1m0s
[03:44:33.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:38:0)
[03:44:33.038] Timer tick, asking Buildbucket for the build status
[03:44:33.415] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:33.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:39:0) after 1m0s
[03:45:33.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:39:0)
[03:45:33.416] Timer tick, asking Buildbucket for the build status
[03:45:33.583] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:33.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:40:0) after 1m0s
[03:46:33.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:40:0)
[03:46:33.650] Timer tick, asking Buildbucket for the build status
[03:46:33.792] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:33.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:41:0) after 1m0s
[03:47:33.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:41:0)
[03:47:33.813] Timer tick, asking Buildbucket for the build status
[03:47:33.916] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:33.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:42:0) after 1m0s
[03:48:33.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:42:0)
[03:48:33.981] Timer tick, asking Buildbucket for the build status
[03:48:34.093] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:34.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:43:0) after 1m0s
[03:49:34.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:43:0)
[03:49:34.164] Timer tick, asking Buildbucket for the build status
[03:49:34.540] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:34.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:44:0) after 1m0s
[03:50:34.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:44:0)
[03:50:34.602] Timer tick, asking Buildbucket for the build status
[03:50:35.034] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:35.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:45:0) after 1m0s
[03:51:35.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:45:0)
[03:51:35.056] Timer tick, asking Buildbucket for the build status
[03:51:35.182] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:35.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:46:0) after 1m0s
[03:52:35.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:46:0)
[03:52:35.214] Timer tick, asking Buildbucket for the build status
[03:52:35.477] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:35.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:47:0) after 1m0s
[03:53:35.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:47:0)
[03:53:35.494] Timer tick, asking Buildbucket for the build status
[03:53:35.703] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:35.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:48:0) after 1m0s
[03:54:35.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:48:0)
[03:54:35.776] Timer tick, asking Buildbucket for the build status
[03:54:35.909] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:35.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:49:0) after 1m0s
[03:55:35.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:49:0)
[03:55:35.980] Timer tick, asking Buildbucket for the build status
[03:55:36.141] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:36.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:50:0) after 1m0s
[03:56:36.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:50:0)
[03:56:36.175] Timer tick, asking Buildbucket for the build status
[03:56:36.384] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:36.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:51:0) after 1m0s
[03:57:36.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:51:0)
[03:57:36.417] Timer tick, asking Buildbucket for the build status
[03:57:36.616] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:36.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:52:0) after 1m0s
[03:58:36.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:52:0)
[03:58:36.634] Timer tick, asking Buildbucket for the build status
[03:58:36.789] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:36.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:53:0) after 1m0s
[03:59:36.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:53:0)
[03:59:36.918] Timer tick, asking Buildbucket for the build status
[03:59:37.018] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:37.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:54:0) after 1m0s
[04:00:37.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:54:0)
[04:00:37.084] Timer tick, asking Buildbucket for the build status
[04:00:37.514] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:37.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:55:0) after 1m0s
[04:01:37.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:55:0)
[04:01:37.593] Timer tick, asking Buildbucket for the build status
[04:01:37.731] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:37.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:56:0) after 1m0s
[04:02:37.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:56:0)
[04:02:37.758] Timer tick, asking Buildbucket for the build status
[04:02:38.140] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:38.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:57:0) after 1m0s
[04:03:38.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:57:0)
[04:03:38.194] Timer tick, asking Buildbucket for the build status
[04:03:38.481] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:38.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:58:0) after 1m0s
[04:04:38.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:58:0)
[04:04:38.553] Timer tick, asking Buildbucket for the build status
[04:04:38.743] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:38.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:59:0) after 1m0s
[04:05:38.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:59:0)
[04:05:38.789] Timer tick, asking Buildbucket for the build status
[04:05:38.917] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:38.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:60:0) after 1m0s
[04:06:38.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:60:0)
[04:06:38.942] Timer tick, asking Buildbucket for the build status
[04:06:39.062] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:39.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:61:0) after 1m0s
[04:07:39.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:61:0)
[04:07:39.116] Timer tick, asking Buildbucket for the build status
[04:07:39.486] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:39.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:62:0) after 1m0s
[04:08:39.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:62:0)
[04:08:39.502] Timer tick, asking Buildbucket for the build status
[04:08:39.610] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:39.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:63:0) after 1m0s
[04:09:39.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:63:0)
[04:09:39.797] Timer tick, asking Buildbucket for the build status
[04:09:39.906] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:39.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:64:0) after 1m0s
[04:10:39.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:64:0)
[04:10:39.946] Timer tick, asking Buildbucket for the build status
[04:10:40.102] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:40.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:65:0) after 1m0s
[04:11:40.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:65:0)
[04:11:40.159] Timer tick, asking Buildbucket for the build status
[04:11:40.272] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:40.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:66:0) after 1m0s
[04:12:40.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:66:0)
[04:12:40.398] Timer tick, asking Buildbucket for the build status
[04:12:40.783] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:40.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:67:0) after 1m0s
[04:13:40.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:67:0)
[04:13:40.903] Timer tick, asking Buildbucket for the build status
[04:13:41.092] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:41.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:68:0) after 1m0s
[04:14:41.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:68:0)
[04:14:41.097] Timer tick, asking Buildbucket for the build status
[04:14:41.227] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:41.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:69:0) after 1m0s
[04:15:41.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:69:0)
[04:15:41.347] Timer tick, asking Buildbucket for the build status
[04:15:41.447] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:41.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:70:0) after 1m0s
[04:16:41.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:70:0)
[04:16:41.527] Timer tick, asking Buildbucket for the build status
[04:16:41.831] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:41.831] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:71:0) after 1m0s
[04:17:41.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:71:0)
[04:17:41.908] Timer tick, asking Buildbucket for the build status
[04:17:42.254] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:42.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:72:0) after 1m0s
[04:18:42.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:72:0)
[04:18:42.270] Timer tick, asking Buildbucket for the build status
[04:18:42.524] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:42.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:73:0) after 1m0s
[04:19:42.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:73:0)
[04:19:42.568] Timer tick, asking Buildbucket for the build status
[04:19:42.687] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:42.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:74:0) after 1m0s
[04:20:42.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:74:0)
[04:20:42.713] Timer tick, asking Buildbucket for the build status
[04:20:42.829] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:42.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:75:0) after 1m0s
[04:21:42.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:75:0)
[04:21:42.867] Timer tick, asking Buildbucket for the build status
[04:21:43.108] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:43.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:76:0) after 1m0s
[04:22:43.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:76:0)
[04:22:43.176] Timer tick, asking Buildbucket for the build status
[04:22:43.624] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:43.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:77:0) after 1m0s
[04:23:43.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:77:0)
[04:23:43.742] Timer tick, asking Buildbucket for the build status
[04:23:44.052] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:44.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:78:0) after 1m0s
[04:24:44.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:78:0)
[04:24:44.182] Timer tick, asking Buildbucket for the build status
[04:24:44.311] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:44.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:79:0) after 1m0s
[04:25:44.399] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:79:0)
[04:25:44.399] Timer tick, asking Buildbucket for the build status
[04:25:44.724] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:44.724] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:80:0) after 1m0s
[04:26:44.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:80:0)
[04:26:44.905] Timer tick, asking Buildbucket for the build status
[04:26:45.067] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:45.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:81:0) after 1m0s
[04:27:45.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:81:0)
[04:27:45.131] Timer tick, asking Buildbucket for the build status
[04:27:45.412] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:45.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:82:0) after 1m0s
[04:28:45.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:82:0)
[04:28:45.499] Timer tick, asking Buildbucket for the build status
[04:28:45.692] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:45.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:83:0) after 1m0s
[04:29:45.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:83:0)
[04:29:45.790] Timer tick, asking Buildbucket for the build status
[04:29:45.913] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:45.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:84:0) after 1m0s
[04:30:46.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:84:0)
[04:30:46.069] Timer tick, asking Buildbucket for the build status
[04:30:46.358] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:46.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:85:0) after 1m0s
[04:31:46.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:85:0)
[04:31:46.470] Timer tick, asking Buildbucket for the build status
[04:31:46.606] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:46.606] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:86:0) after 1m0s
[04:32:46.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:86:0)
[04:32:46.642] Timer tick, asking Buildbucket for the build status
[04:32:46.784] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:46.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:87:0) after 1m0s
[04:33:46.821] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:87:0)
[04:33:46.821] Timer tick, asking Buildbucket for the build status
[04:33:47.012] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:47.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:88:0) after 1m0s
[04:34:47.179] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:88:0)
[04:34:47.179] Timer tick, asking Buildbucket for the build status
[04:34:47.284] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:47.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:89:0) after 1m0s
[04:35:47.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:89:0)
[04:35:47.379] Timer tick, asking Buildbucket for the build status
[04:35:47.495] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:47.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:90:0) after 1m0s
[04:36:47.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:90:0)
[04:36:47.544] Timer tick, asking Buildbucket for the build status
[04:36:47.787] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:47.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:91:0) after 1m0s
[04:37:47.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:91:0)
[04:37:47.828] Timer tick, asking Buildbucket for the build status
[04:37:47.928] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:47.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:92:0) after 1m0s
[04:38:47.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:92:0)
[04:38:47.975] Timer tick, asking Buildbucket for the build status
[04:38:48.081] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:48.081] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:93:0) after 1m0s
[04:39:48.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:93:0)
[04:39:48.115] Timer tick, asking Buildbucket for the build status
[04:39:48.214] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:48.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:94:0) after 1m0s
[04:40:48.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:94:0)
[04:40:48.293] Timer tick, asking Buildbucket for the build status
[04:40:48.540] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:48.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:95:0) after 1m0s
[04:41:48.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:95:0)
[04:41:48.576] Timer tick, asking Buildbucket for the build status
[04:41:48.778] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:48.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:96:0) after 1m0s
[04:42:48.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:96:0)
[04:42:48.824] Timer tick, asking Buildbucket for the build status
[04:42:49.170] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:49.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:97:0) after 1m0s
[04:43:49.337] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:97:0)
[04:43:49.337] Timer tick, asking Buildbucket for the build status
[04:43:49.525] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:49.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:98:0) after 1m0s
[04:44:49.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:98:0)
[04:44:49.673] Timer tick, asking Buildbucket for the build status
[04:44:49.764] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:49.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:99:0) after 1m0s
[04:45:49.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:99:0)
[04:45:49.841] Timer tick, asking Buildbucket for the build status
[04:45:49.947] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:49.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:100:0) after 1m0s
[04:46:50.069] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:100:0)
[04:46:50.069] Timer tick, asking Buildbucket for the build status
[04:46:50.157] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:50.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:101:0) after 1m0s
[04:47:50.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:101:0)
[04:47:50.335] Timer tick, asking Buildbucket for the build status
[04:47:50.431] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:50.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:102:0) after 1m0s
[04:48:50.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:102:0)
[04:48:50.682] Timer tick, asking Buildbucket for the build status
[04:48:50.814] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:50.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:103:0) after 1m0s
[04:49:50.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:103:0)
[04:49:50.819] Timer tick, asking Buildbucket for the build status
[04:49:51.146] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:51.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:104:0) after 1m0s
[04:50:51.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:104:0)
[04:50:51.230] Timer tick, asking Buildbucket for the build status
[04:50:51.387] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:51.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:105:0) after 1m0s
[04:51:51.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:105:0)
[04:51:51.507] Timer tick, asking Buildbucket for the build status
[04:51:51.603] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:51.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:106:0) after 1m0s
[04:52:51.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:106:0)
[04:52:51.740] Timer tick, asking Buildbucket for the build status
[04:52:51.836] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:51.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:107:0) after 1m0s
[04:53:51.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:107:0)
[04:53:51.909] Timer tick, asking Buildbucket for the build status
[04:53:52.008] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:52.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:108:0) after 1m0s
[04:54:52.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:108:0)
[04:54:52.172] Timer tick, asking Buildbucket for the build status
[04:54:52.266] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:52.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:109:0) after 1m0s
[04:55:52.312] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:109:0)
[04:55:52.312] Timer tick, asking Buildbucket for the build status
[04:55:52.616] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:52.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:110:0) after 1m0s
[04:56:52.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:110:0)
[04:56:52.619] Timer tick, asking Buildbucket for the build status
[04:56:53.037] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:53.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:111:0) after 1m0s
[04:57:53.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:111:0)
[04:57:53.078] Timer tick, asking Buildbucket for the build status
[04:57:53.186] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:53.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:112:0) after 1m0s
[04:58:53.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:112:0)
[04:58:53.176] Timer tick, asking Buildbucket for the build status
[04:58:53.470] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:53.470] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:113:0) after 1m0s
[04:59:53.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:113:0)
[04:59:53.526] Timer tick, asking Buildbucket for the build status
[04:59:53.650] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:53.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:114:0) after 1m0s
[05:00:53.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:114:0)
[05:00:53.777] Timer tick, asking Buildbucket for the build status
[05:00:54.016] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:54.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:115:0) after 1m0s
[05:01:54.097] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:115:0)
[05:01:54.097] Timer tick, asking Buildbucket for the build status
[05:01:54.206] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:54.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:116:0) after 1m0s
[05:02:54.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:116:0)
[05:02:54.392] Timer tick, asking Buildbucket for the build status
[05:02:54.503] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:54.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:117:0) after 1m0s
[05:03:54.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:117:0)
[05:03:54.546] Timer tick, asking Buildbucket for the build status
[05:03:54.651] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:54.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:118:0) after 1m0s
[05:04:54.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:118:0)
[05:04:54.670] Timer tick, asking Buildbucket for the build status
[05:04:54.764] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:54.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:119:0) after 1m0s
[05:05:54.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:119:0)
[05:05:54.867] Timer tick, asking Buildbucket for the build status
[05:05:55.093] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:55.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:120:0) after 1m0s
[05:06:55.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:120:0)
[05:06:55.124] Timer tick, asking Buildbucket for the build status
[05:06:55.224] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:55.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:121:0) after 1m0s
[05:07:55.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:121:0)
[05:07:55.276] Timer tick, asking Buildbucket for the build status
[05:07:55.448] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:55.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:122:0) after 1m0s
[05:08:55.501] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:122:0)
[05:08:55.501] Timer tick, asking Buildbucket for the build status
[05:08:55.602] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:55.602] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:123:0) after 1m0s
[05:09:55.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:123:0)
[05:09:55.627] Timer tick, asking Buildbucket for the build status
[05:09:56.060] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:56.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:124:0) after 1m0s
[05:10:56.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:124:0)
[05:10:56.083] Timer tick, asking Buildbucket for the build status
[05:10:56.363] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:56.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:125:0) after 1m0s
[05:11:56.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:125:0)
[05:11:56.371] Timer tick, asking Buildbucket for the build status
[05:11:56.457] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:56.457] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:126:0) after 1m0s
[05:12:56.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:126:0)
[05:12:56.573] Timer tick, asking Buildbucket for the build status
[05:12:56.677] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:56.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:127:0) after 1m0s
[05:13:56.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:127:0)
[05:13:56.701] Timer tick, asking Buildbucket for the build status
[05:13:56.791] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:56.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:128:0) after 1m0s
[05:14:56.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:128:0)
[05:14:56.864] Timer tick, asking Buildbucket for the build status
[05:14:56.992] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:56.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:129:0) after 1m0s
[05:15:57.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:129:0)
[05:15:57.150] Timer tick, asking Buildbucket for the build status
[05:15:57.313] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:57.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:130:0) after 1m0s
[05:16:57.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:130:0)
[05:16:57.404] Timer tick, asking Buildbucket for the build status
[05:16:57.500] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:57.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:131:0) after 1m0s
[05:17:57.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:131:0)
[05:17:57.559] Timer tick, asking Buildbucket for the build status
[05:17:57.718] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:57.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:132:0) after 1m0s
[05:18:57.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:132:0)
[05:18:57.816] Timer tick, asking Buildbucket for the build status
[05:18:58.163] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:18:58.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:133:0) after 1m0s
[05:19:58.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:133:0)
[05:19:58.217] Timer tick, asking Buildbucket for the build status
[05:19:58.343] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:19:58.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:134:0) after 1m0s
[05:20:58.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:134:0)
[05:20:58.364] Timer tick, asking Buildbucket for the build status
[05:20:58.608] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:58.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:135:0) after 1m0s
[05:21:58.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:135:0)
[05:21:58.646] Timer tick, asking Buildbucket for the build status
[05:21:58.979] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:58.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:136:0) after 1m0s
[05:22:59.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:136:0)
[05:22:59.037] Timer tick, asking Buildbucket for the build status
[05:22:59.362] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:59.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:137:0) after 1m0s
[05:23:59.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:137:0)
[05:23:59.468] Timer tick, asking Buildbucket for the build status
[05:23:59.706] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:59.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:138:0) after 1m0s
[05:24:59.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:138:0)
[05:24:59.743] Timer tick, asking Buildbucket for the build status
[05:24:59.893] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:59.893] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:139:0) after 1m0s
[05:25:59.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:139:0)
[05:25:59.918] Timer tick, asking Buildbucket for the build status
[05:26:00.240] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:00.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:140:0) after 1m0s
[05:27:00.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:140:0)
[05:27:00.274] Timer tick, asking Buildbucket for the build status
[05:27:00.414] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:00.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:141:0) after 1m0s
[05:28:00.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:141:0)
[05:28:00.481] Timer tick, asking Buildbucket for the build status
[05:28:00.823] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:00.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:142:0) after 1m0s
[05:29:00.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:142:0)
[05:29:00.879] Timer tick, asking Buildbucket for the build status
[05:29:00.984] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:00.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:143:0) after 1m0s
[05:30:01.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:143:0)
[05:30:01.005] Timer tick, asking Buildbucket for the build status
[05:30:01.272] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:01.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:144:0) after 1m0s
[05:31:01.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:144:0)
[05:31:01.361] Timer tick, asking Buildbucket for the build status
[05:31:01.515] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:01.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:145:0) after 1m0s
[05:32:01.567] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:145:0)
[05:32:01.567] Timer tick, asking Buildbucket for the build status
[05:32:01.682] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:01.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:146:0) after 1m0s
[05:33:01.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:146:0)
[05:33:01.748] Timer tick, asking Buildbucket for the build status
[05:33:02.040] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:02.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:147:0) after 1m0s
[05:34:02.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:147:0)
[05:34:02.107] Timer tick, asking Buildbucket for the build status
[05:34:02.224] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:02.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:148:0) after 1m0s
[05:35:02.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:148:0)
[05:35:02.334] Timer tick, asking Buildbucket for the build status
[05:35:02.440] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:02.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:149:0) after 1m0s
[05:36:02.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:149:0)
[05:36:02.513] Timer tick, asking Buildbucket for the build status
[05:36:02.637] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:02.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:150:0) after 1m0s
[05:37:02.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:150:0)
[05:37:02.758] Timer tick, asking Buildbucket for the build status
[05:37:03.038] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:03.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:151:0) after 1m0s
[05:38:03.074] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:151:0)
[05:38:03.074] Timer tick, asking Buildbucket for the build status
[05:38:03.182] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:03.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:152:0) after 1m0s
[05:39:03.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:152:0)
[05:39:03.206] Timer tick, asking Buildbucket for the build status
[05:39:03.650] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:03.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:153:0) after 1m0s
[05:40:03.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:153:0)
[05:40:03.682] Timer tick, asking Buildbucket for the build status
[05:40:03.786] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:03.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:154:0) after 1m0s
[05:41:03.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:154:0)
[05:41:03.837] Timer tick, asking Buildbucket for the build status
[05:41:04.018] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:04.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:155:0) after 1m0s
[05:42:04.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:155:0)
[05:42:04.072] Timer tick, asking Buildbucket for the build status
[05:42:04.191] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:04.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:156:0) after 1m0s
[05:43:04.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:156:0)
[05:43:04.252] Timer tick, asking Buildbucket for the build status
[05:43:04.639] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:04.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:157:0) after 1m0s
[05:44:04.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:157:0)
[05:44:04.673] Timer tick, asking Buildbucket for the build status
[05:44:04.769] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:04.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:158:0) after 1m0s
[05:45:04.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:158:0)
[05:45:04.796] Timer tick, asking Buildbucket for the build status
[05:45:04.890] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:04.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:159:0) after 1m0s
[05:46:04.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:159:0)
[05:46:04.938] Timer tick, asking Buildbucket for the build status
[05:46:05.169] Build 8887336099180903520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:05.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:160:0) after 1m0s
[05:46:38.746] Received PubSub notification, asking Buildbucket for the build status
[05:46:38.826] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:05.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:160:0)
[05:47:05.210] Timer tick, asking Buildbucket for the build status
[05:47:05.440] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:05.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:162:0) after 1m0s
[05:48:05.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:162:0)
[05:48:05.533] Timer tick, asking Buildbucket for the build status
[05:48:05.665] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:05.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:163:0) after 1m0s
[05:49:05.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:163:0)
[05:49:05.749] Timer tick, asking Buildbucket for the build status
[05:49:05.856] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:05.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:164:0) after 1m0s
[05:50:05.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:164:0)
[05:50:05.860] Timer tick, asking Buildbucket for the build status
[05:50:05.962] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:05.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:165:0) after 1m0s
[05:51:06.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:165:0)
[05:51:06.063] Timer tick, asking Buildbucket for the build status
[05:51:06.193] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:06.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:166:0) after 1m0s
[05:52:06.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:166:0)
[05:52:06.231] Timer tick, asking Buildbucket for the build status
[05:52:06.406] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:06.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:167:0) after 1m0s
[05:53:06.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:167:0)
[05:53:06.449] Timer tick, asking Buildbucket for the build status
[05:53:06.563] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:06.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:168:0) after 1m0s
[05:54:06.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:168:0)
[05:54:06.626] Timer tick, asking Buildbucket for the build status
[05:54:06.840] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:06.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:169:0) after 1m0s
[05:55:06.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:169:0)
[05:55:06.907] Timer tick, asking Buildbucket for the build status
[05:55:07.472] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:07.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:170:0) after 1m0s
[05:56:07.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:170:0)
[05:56:07.615] Timer tick, asking Buildbucket for the build status
[05:56:07.898] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:07.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:171:0) after 1m0s
[05:57:07.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:171:0)
[05:57:07.969] Timer tick, asking Buildbucket for the build status
[05:57:08.101] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:08.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:172:0) after 1m0s
[05:58:08.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:172:0)
[05:58:08.145] Timer tick, asking Buildbucket for the build status
[05:58:08.398] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:08.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:173:0) after 1m0s
[05:59:08.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:173:0)
[05:59:08.454] Timer tick, asking Buildbucket for the build status
[05:59:09.795] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:09.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:174:0) after 1m0s
[06:00:09.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:174:0)
[06:00:09.865] Timer tick, asking Buildbucket for the build status
[06:00:09.966] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:09.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:175:0) after 1m0s
[06:01:10.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:175:0)
[06:01:10.032] Timer tick, asking Buildbucket for the build status
[06:01:10.185] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:10.185] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:176:0) after 1m0s
[06:02:10.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:176:0)
[06:02:10.241] Timer tick, asking Buildbucket for the build status
[06:02:10.367] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:10.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:177:0) after 1m0s
[06:03:10.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:177:0)
[06:03:10.443] Timer tick, asking Buildbucket for the build status
[06:03:10.972] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:10.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:178:0) after 1m0s
[06:04:11.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:178:0)
[06:04:11.094] Timer tick, asking Buildbucket for the build status
[06:04:11.571] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:11.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:179:0) after 1m0s
[06:05:11.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:179:0)
[06:05:11.636] Timer tick, asking Buildbucket for the build status
[06:05:12.318] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:12.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:180:0) after 1m0s
[06:06:12.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:180:0)
[06:06:12.334] Timer tick, asking Buildbucket for the build status
[06:06:12.413] Build 8887336099180903520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:12.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_32-tests-mips:9052766162336806176:181:0) after 1m0s
[06:06:20.167] Received PubSub notification, asking Buildbucket for the build status
[06:06:20.301] Build 8887336099180903520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:06:20.301] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1582783578882444",
    "created_by": "project:nacl",
    "created_ts": "1582772843149583",
    "id": "8887336099180903520",
    "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\": \"d3db562593968b5b8761117d31f96eae305c37a7\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d3db562593968b5b8761117d31f96eae305c37a7\", \"title\": \"d3db562593968b5b8761117d31f96eae305c37a7\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d3db562593968b5b8761117d31f96eae305c37a7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d3db562593968b5b8761117d31f96eae305c37a7\"}}",
    "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\": \"d3db562593968b5b8761117d31f96eae305c37a7\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@d3db562593968b5b8761117d31f96eae305c37a7\", \"title\": \"d3db562593968b5b8761117d31f96eae305c37a7\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/d3db562593968b5b8761117d31f96eae305c37a7\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-2i3m\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_32-tests-mips\", \"buildnumber\": 4978, \"got_revision\": \"d3db562593968b5b8761117d31f96eae305c37a7\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"d3db562593968b5b8761117d31f96eae305c37a7\", \"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-2i3m\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4838-b69615e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1582782398141527",
    "status": "COMPLETED",
    "status_changed_ts": "1582783579795916",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_32-tests-mips/4978",
      "builder:linux-pnacl-x86_32-tests-mips",
      "buildset:commit/git/d3db562593968b5b8761117d31f96eae305c37a7",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/d3db562593968b5b8761117d31f96eae305c37a7",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9052766162336806176",
      "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/8887336099180903520/+/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:4a9d6e8800c02210",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1582783579805686",
    "url": "https://ci.chromium.org/b/8887336099180903520",
    "utcnow_ts": "1582783580288349"
  }
}
[06:06:20.301] Invocation finished in 2h58m59.546271267s with status SUCCEEDED