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

• 55301a02435278dd3cb922da180df973df20ca70 (4 years ago)

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

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

[00:10:10.175] New invocation is queued and will start shortly
[00:10:11.586] Starting the invocation (attempt 1)
[00:10:11.625] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:10:11.625] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:10:11.625] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9050421788201830320",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"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\":\"55301a02435278dd3cb922da180df973df20ca70\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@55301a02435278dd3cb922da180df973df20ca70\",\"title\":\"55301a02435278dd3cb922da180df973df20ca70\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/55301a02435278dd3cb922da180df973df20ca70\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"55301a02435278dd3cb922da180df973df20ca70\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9050421788201830320",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/55301a02435278dd3cb922da180df973df20ca70",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55301a02435278dd3cb922da180df973df20ca70",
    "gitiles_ref:refs/heads/master"
  ]
}
[00:10:13.093] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585008611782208",
    "id": "8884991725850654032",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"55301a02435278dd3cb922da180df973df20ca70\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@55301a02435278dd3cb922da180df973df20ca70\", \"title\": \"55301a02435278dd3cb922da180df973df20ca70\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/55301a02435278dd3cb922da180df973df20ca70\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"55301a02435278dd3cb922da180df973df20ca70\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585008612632551",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5146",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/55301a02435278dd3cb922da180df973df20ca70",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55301a02435278dd3cb922da180df973df20ca70",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050421788201830320",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884991725850654032/+/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": "1585008612632695",
    "url": "https://ci.chromium.org/b/8884991725850654032",
    "utcnow_ts": "1585008613074595"
  }
}
[00:10:13.093] Task URL: https://ci.chromium.org/b/8884991725850654032
[00:10:13.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:2:0) after 1m0s
[00:11:13.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:2:0)
[00:11:13.169] Timer tick, asking Buildbucket for the build status
[00:11:13.502] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:13.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:3:0) after 1m0s
[00:12:13.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:3:0)
[00:12:13.525] Timer tick, asking Buildbucket for the build status
[00:12:13.706] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:13.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:4:0) after 1m0s
[00:13:13.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:4:0)
[00:13:13.728] Timer tick, asking Buildbucket for the build status
[00:13:13.902] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:13.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:5:0) after 1m0s
[00:14:13.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:5:0)
[00:14:13.944] Timer tick, asking Buildbucket for the build status
[00:14:14.407] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:14.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:6:0) after 1m0s
[00:15:14.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:6:0)
[00:15:14.410] Timer tick, asking Buildbucket for the build status
[00:15:14.575] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:14.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:7:0) after 1m0s
[00:16:14.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:7:0)
[00:16:14.623] Timer tick, asking Buildbucket for the build status
[00:16:14.775] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:14.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:8:0) after 1m0s
[00:17:14.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:8:0)
[00:17:14.844] Timer tick, asking Buildbucket for the build status
[00:17:15.055] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:15.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:9:0) after 1m0s
[00:18:15.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:9:0)
[00:18:15.108] Timer tick, asking Buildbucket for the build status
[00:18:15.229] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:15.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:10:0) after 1m0s
[00:19:15.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:10:0)
[00:19:15.336] Timer tick, asking Buildbucket for the build status
[00:19:15.452] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:15.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:11:0) after 1m0s
[00:20:15.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:11:0)
[00:20:15.462] Timer tick, asking Buildbucket for the build status
[00:20:15.578] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:15.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:12:0) after 1m0s
[00:21:15.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:12:0)
[00:21:15.602] Timer tick, asking Buildbucket for the build status
[00:21:15.727] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:15.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:13:0) after 1m0s
[00:22:16.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:13:0)
[00:22:16.015] Timer tick, asking Buildbucket for the build status
[00:22:16.481] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:16.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:14:0) after 1m0s
[00:23:16.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:14:0)
[00:23:16.601] Timer tick, asking Buildbucket for the build status
[00:23:16.728] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:16.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:15:0) after 1m0s
[00:24:16.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:15:0)
[00:24:16.977] Timer tick, asking Buildbucket for the build status
[00:24:17.082] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:17.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:16:0) after 1m0s
[00:25:17.400] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:16:0)
[00:25:17.400] Timer tick, asking Buildbucket for the build status
[00:25:17.560] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:17.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:17:0) after 1m0s
[00:26:17.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:17:0)
[00:26:17.663] Timer tick, asking Buildbucket for the build status
[00:26:17.925] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:17.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:18:0) after 1m0s
[00:27:17.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:18:0)
[00:27:17.978] Timer tick, asking Buildbucket for the build status
[00:27:18.132] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:18.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:19:0) after 1m0s
[00:28:18.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:19:0)
[00:28:18.150] Timer tick, asking Buildbucket for the build status
[00:28:18.246] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:18.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:20:0) after 1m0s
[00:29:18.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:20:0)
[00:29:18.293] Timer tick, asking Buildbucket for the build status
[00:29:18.654] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:18.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:21:0) after 1m0s
[00:30:18.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:21:0)
[00:30:18.682] Timer tick, asking Buildbucket for the build status
[00:30:18.798] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:18.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:22:0) after 1m0s
[00:31:18.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:22:0)
[00:31:18.836] Timer tick, asking Buildbucket for the build status
[00:31:18.923] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:18.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:23:0) after 1m0s
[00:32:19.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:23:0)
[00:32:19.118] Timer tick, asking Buildbucket for the build status
[00:32:19.289] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:19.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:24:0) after 1m0s
[00:33:19.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:24:0)
[00:33:19.601] Timer tick, asking Buildbucket for the build status
[00:33:19.754] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:19.754] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:25:0) after 1m0s
[00:34:19.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:25:0)
[00:34:19.966] Timer tick, asking Buildbucket for the build status
[00:34:20.142] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:20.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:26:0) after 1m0s
[00:35:20.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:26:0)
[00:35:20.754] Timer tick, asking Buildbucket for the build status
[00:35:20.845] Build 8884991725850654032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:20.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:27:0) after 1m0s
[00:35:49.328] Received PubSub notification, asking Buildbucket for the build status
[00:35:49.394] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:20.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:27:0)
[00:36:20.938] Timer tick, asking Buildbucket for the build status
[00:36:21.066] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:21.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:29:0) after 1m0s
[00:37:21.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:29:0)
[00:37:21.174] Timer tick, asking Buildbucket for the build status
[00:37:21.277] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:21.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:30:0) after 1m0s
[00:38:21.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:30:0)
[00:38:21.433] Timer tick, asking Buildbucket for the build status
[00:38:21.671] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:21.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:31:0) after 1m0s
[00:39:21.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:31:0)
[00:39:21.745] Timer tick, asking Buildbucket for the build status
[00:39:22.201] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:22.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:32:0) after 1m0s
[00:40:22.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:32:0)
[00:40:22.253] Timer tick, asking Buildbucket for the build status
[00:40:22.444] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:22.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:33:0) after 1m0s
[00:41:22.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:33:0)
[00:41:22.524] Timer tick, asking Buildbucket for the build status
[00:41:22.729] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:22.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:34:0) after 1m0s
[00:42:22.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:34:0)
[00:42:22.748] Timer tick, asking Buildbucket for the build status
[00:42:22.857] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:22.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:35:0) after 1m0s
[00:43:22.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:35:0)
[00:43:22.865] Timer tick, asking Buildbucket for the build status
[00:43:22.956] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:22.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:36:0) after 1m0s
[00:44:23.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:36:0)
[00:44:23.104] Timer tick, asking Buildbucket for the build status
[00:44:23.317] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:23.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:37:0) after 1m0s
[00:45:23.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:37:0)
[00:45:23.339] Timer tick, asking Buildbucket for the build status
[00:45:23.542] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:23.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:38:0) after 1m0s
[00:46:23.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:38:0)
[00:46:23.591] Timer tick, asking Buildbucket for the build status
[00:46:23.834] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:23.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:39:0) after 1m0s
[00:47:23.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:39:0)
[00:47:23.870] Timer tick, asking Buildbucket for the build status
[00:47:24.015] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:24.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:40:0) after 1m0s
[00:48:24.122] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:40:0)
[00:48:24.122] Timer tick, asking Buildbucket for the build status
[00:48:24.211] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:24.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:41:0) after 1m0s
[00:49:24.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:41:0)
[00:49:24.282] Timer tick, asking Buildbucket for the build status
[00:49:24.423] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:24.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:42:0) after 1m0s
[00:50:24.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:42:0)
[00:50:24.464] Timer tick, asking Buildbucket for the build status
[00:50:24.801] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:24.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:43:0) after 1m0s
[00:51:24.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:43:0)
[00:51:24.831] Timer tick, asking Buildbucket for the build status
[00:51:24.972] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:24.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:44:0) after 1m0s
[00:52:24.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:44:0)
[00:52:24.997] Timer tick, asking Buildbucket for the build status
[00:52:25.155] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:25.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:45:0) after 1m0s
[00:53:25.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:45:0)
[00:53:25.146] Timer tick, asking Buildbucket for the build status
[00:53:25.295] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:25.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:46:0) after 1m0s
[00:54:25.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:46:0)
[00:54:25.320] Timer tick, asking Buildbucket for the build status
[00:54:25.705] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:25.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:47:0) after 1m0s
[00:55:25.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:47:0)
[00:55:25.805] Timer tick, asking Buildbucket for the build status
[00:55:25.998] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:25.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:48:0) after 1m0s
[00:56:26.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:48:0)
[00:56:26.028] Timer tick, asking Buildbucket for the build status
[00:56:26.433] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:26.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:49:0) after 1m0s
[00:57:26.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:49:0)
[00:57:26.458] Timer tick, asking Buildbucket for the build status
[00:57:26.599] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:26.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:50:0) after 1m0s
[00:58:26.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:50:0)
[00:58:26.623] Timer tick, asking Buildbucket for the build status
[00:58:26.798] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:26.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:51:0) after 1m0s
[00:59:26.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:51:0)
[00:59:26.864] Timer tick, asking Buildbucket for the build status
[00:59:27.982] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:27.982] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:52:0) after 1m0s
[01:00:28.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:52:0)
[01:00:28.128] Timer tick, asking Buildbucket for the build status
[01:00:28.227] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:28.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:53:0) after 1m0s
[01:01:28.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:53:0)
[01:01:28.259] Timer tick, asking Buildbucket for the build status
[01:01:28.402] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:28.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:54:0) after 1m0s
[01:02:28.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:54:0)
[01:02:28.439] Timer tick, asking Buildbucket for the build status
[01:02:28.579] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:28.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:55:0) after 1m0s
[01:03:28.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:55:0)
[01:03:28.598] Timer tick, asking Buildbucket for the build status
[01:03:28.713] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:28.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:56:0) after 1m0s
[01:04:28.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:56:0)
[01:04:28.775] Timer tick, asking Buildbucket for the build status
[01:04:28.913] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:28.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:57:0) after 1m0s
[01:05:28.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:57:0)
[01:05:28.906] Timer tick, asking Buildbucket for the build status
[01:05:28.969] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:28.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:58:0) after 1m0s
[01:06:29.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:58:0)
[01:06:29.045] Timer tick, asking Buildbucket for the build status
[01:06:29.199] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:29.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:59:0) after 1m0s
[01:07:29.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:59:0)
[01:07:29.207] Timer tick, asking Buildbucket for the build status
[01:07:29.312] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:29.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:60:0) after 1m0s
[01:08:29.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:60:0)
[01:08:29.428] Timer tick, asking Buildbucket for the build status
[01:08:29.559] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:29.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:61:0) after 1m0s
[01:09:29.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:61:0)
[01:09:29.585] Timer tick, asking Buildbucket for the build status
[01:09:29.759] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:29.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:62:0) after 1m0s
[01:10:29.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:62:0)
[01:10:29.783] Timer tick, asking Buildbucket for the build status
[01:10:30.048] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:30.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:63:0) after 1m0s
[01:11:30.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:63:0)
[01:11:30.076] Timer tick, asking Buildbucket for the build status
[01:11:30.421] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:30.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:64:0) after 1m0s
[01:12:30.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:64:0)
[01:12:30.405] Timer tick, asking Buildbucket for the build status
[01:12:30.549] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:30.549] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:65:0) after 1m0s
[01:13:30.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:65:0)
[01:13:30.609] Timer tick, asking Buildbucket for the build status
[01:13:30.785] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:30.785] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:66:0) after 1m0s
[01:14:30.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:66:0)
[01:14:30.838] Timer tick, asking Buildbucket for the build status
[01:14:31.133] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:31.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:67:0) after 1m0s
[01:15:31.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:67:0)
[01:15:31.167] Timer tick, asking Buildbucket for the build status
[01:15:31.358] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:31.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:68:0) after 1m0s
[01:16:31.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:68:0)
[01:16:31.628] Timer tick, asking Buildbucket for the build status
[01:16:31.880] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:31.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:69:0) after 1m0s
[01:17:31.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:69:0)
[01:17:31.992] Timer tick, asking Buildbucket for the build status
[01:17:32.148] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:32.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:70:0) after 1m0s
[01:18:32.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:70:0)
[01:18:32.346] Timer tick, asking Buildbucket for the build status
[01:18:32.464] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:32.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:71:0) after 1m0s
[01:19:32.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:71:0)
[01:19:32.475] Timer tick, asking Buildbucket for the build status
[01:19:32.951] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:32.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:72:0) after 1m0s
[01:20:32.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:72:0)
[01:20:32.984] Timer tick, asking Buildbucket for the build status
[01:20:33.312] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:33.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:73:0) after 1m0s
[01:21:33.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:73:0)
[01:21:33.353] Timer tick, asking Buildbucket for the build status
[01:21:34.172] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:34.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:74:0) after 1m0s
[01:22:34.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:74:0)
[01:22:34.236] Timer tick, asking Buildbucket for the build status
[01:22:34.472] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:34.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:75:0) after 1m0s
[01:23:34.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:75:0)
[01:23:34.566] Timer tick, asking Buildbucket for the build status
[01:23:34.751] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:34.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:76:0) after 1m0s
[01:24:34.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:76:0)
[01:24:34.885] Timer tick, asking Buildbucket for the build status
[01:24:35.218] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:35.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:77:0) after 1m0s
[01:25:35.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:77:0)
[01:25:35.328] Timer tick, asking Buildbucket for the build status
[01:25:35.579] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:35.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:78:0) after 1m0s
[01:26:35.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:78:0)
[01:26:35.647] Timer tick, asking Buildbucket for the build status
[01:26:35.799] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:35.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:79:0) after 1m0s
[01:27:35.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:79:0)
[01:27:35.836] Timer tick, asking Buildbucket for the build status
[01:27:36.289] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:36.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:80:0) after 1m0s
[01:28:36.370] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:80:0)
[01:28:36.370] Timer tick, asking Buildbucket for the build status
[01:28:36.925] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:36.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:81:0) after 1m0s
[01:29:37.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:81:0)
[01:29:37.015] Timer tick, asking Buildbucket for the build status
[01:29:38.164] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:38.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:82:0) after 1m0s
[01:30:38.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:82:0)
[01:30:38.267] Timer tick, asking Buildbucket for the build status
[01:30:38.437] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:38.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:83:0) after 1m0s
[01:31:38.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:83:0)
[01:31:38.474] Timer tick, asking Buildbucket for the build status
[01:31:38.615] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:38.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:84:0) after 1m0s
[01:32:38.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:84:0)
[01:32:38.627] Timer tick, asking Buildbucket for the build status
[01:32:38.744] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:38.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:85:0) after 1m0s
[01:33:38.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:85:0)
[01:33:38.789] Timer tick, asking Buildbucket for the build status
[01:33:38.924] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:38.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:86:0) after 1m0s
[01:34:39.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:86:0)
[01:34:39.038] Timer tick, asking Buildbucket for the build status
[01:34:39.593] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:39.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:87:0) after 1m0s
[01:35:39.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:87:0)
[01:35:39.690] Timer tick, asking Buildbucket for the build status
[01:35:39.820] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:39.820] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:88:0) after 1m0s
[01:36:39.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:88:0)
[01:36:39.857] Timer tick, asking Buildbucket for the build status
[01:36:40.284] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:40.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:89:0) after 1m0s
[01:37:40.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:89:0)
[01:37:40.320] Timer tick, asking Buildbucket for the build status
[01:37:40.411] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:40.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:90:0) after 1m0s
[01:38:40.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:90:0)
[01:38:40.493] Timer tick, asking Buildbucket for the build status
[01:38:40.611] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:40.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:91:0) after 1m0s
[01:39:40.737] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:91:0)
[01:39:40.737] Timer tick, asking Buildbucket for the build status
[01:39:40.869] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:40.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:92:0) after 1m0s
[01:40:40.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:92:0)
[01:40:40.950] Timer tick, asking Buildbucket for the build status
[01:40:41.448] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:41.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:93:0) after 1m0s
[01:41:41.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:93:0)
[01:41:41.497] Timer tick, asking Buildbucket for the build status
[01:41:43.108] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:43.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:94:0) after 1m0s
[01:42:43.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:94:0)
[01:42:43.137] Timer tick, asking Buildbucket for the build status
[01:42:43.291] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:43.291] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:95:0) after 1m0s
[01:43:43.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:95:0)
[01:43:43.373] Timer tick, asking Buildbucket for the build status
[01:43:43.499] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:43.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:96:0) after 1m0s
[01:44:43.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:96:0)
[01:44:43.493] Timer tick, asking Buildbucket for the build status
[01:44:43.951] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:43.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:97:0) after 1m0s
[01:45:43.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:97:0)
[01:45:43.931] Timer tick, asking Buildbucket for the build status
[01:45:44.146] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:44.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:98:0) after 1m0s
[01:46:44.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:98:0)
[01:46:44.207] Timer tick, asking Buildbucket for the build status
[01:46:44.331] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:44.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:99:0) after 1m0s
[01:47:44.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:99:0)
[01:47:44.417] Timer tick, asking Buildbucket for the build status
[01:47:44.510] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:44.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:100:0) after 1m0s
[01:48:44.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:100:0)
[01:48:44.559] Timer tick, asking Buildbucket for the build status
[01:48:44.700] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:44.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:101:0) after 1m0s
[01:49:44.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:101:0)
[01:49:44.789] Timer tick, asking Buildbucket for the build status
[01:49:45.462] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:45.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:102:0) after 1m0s
[01:50:45.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:102:0)
[01:50:45.483] Timer tick, asking Buildbucket for the build status
[01:50:45.616] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:45.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:103:0) after 1m0s
[01:51:45.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:103:0)
[01:51:45.638] Timer tick, asking Buildbucket for the build status
[01:51:45.811] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:45.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:104:0) after 1m0s
[01:52:45.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:104:0)
[01:52:45.965] Timer tick, asking Buildbucket for the build status
[01:52:46.156] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:46.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:105:0) after 1m0s
[01:53:46.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:105:0)
[01:53:46.212] Timer tick, asking Buildbucket for the build status
[01:53:46.282] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:46.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:106:0) after 1m0s
[01:54:46.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:106:0)
[01:54:46.304] Timer tick, asking Buildbucket for the build status
[01:54:47.318] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:47.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:107:0) after 1m0s
[01:55:47.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:107:0)
[01:55:47.343] Timer tick, asking Buildbucket for the build status
[01:55:47.531] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:47.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:108:0) after 1m0s
[01:56:47.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:108:0)
[01:56:47.556] Timer tick, asking Buildbucket for the build status
[01:56:48.129] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:48.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:109:0) after 1m0s
[01:57:48.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:109:0)
[01:57:48.187] Timer tick, asking Buildbucket for the build status
[01:57:48.777] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:48.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:110:0) after 1m0s
[01:58:48.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:110:0)
[01:58:48.915] Timer tick, asking Buildbucket for the build status
[01:58:49.194] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:49.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:111:0) after 1m0s
[01:59:49.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:111:0)
[01:59:49.261] Timer tick, asking Buildbucket for the build status
[01:59:49.431] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:49.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:112:0) after 1m0s
[02:00:49.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:112:0)
[02:00:49.473] Timer tick, asking Buildbucket for the build status
[02:00:49.600] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:49.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:113:0) after 1m0s
[02:01:49.622] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:113:0)
[02:01:49.622] Timer tick, asking Buildbucket for the build status
[02:01:49.778] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:49.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:114:0) after 1m0s
[02:02:49.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:114:0)
[02:02:49.801] Timer tick, asking Buildbucket for the build status
[02:02:49.960] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:49.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:115:0) after 1m0s
[02:03:50.010] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:115:0)
[02:03:50.011] Timer tick, asking Buildbucket for the build status
[02:03:50.145] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:50.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:116:0) after 1m0s
[02:04:50.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:116:0)
[02:04:50.308] Timer tick, asking Buildbucket for the build status
[02:04:50.612] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:50.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:117:0) after 1m0s
[02:05:50.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:117:0)
[02:05:50.644] Timer tick, asking Buildbucket for the build status
[02:05:50.790] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:50.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:118:0) after 1m0s
[02:06:50.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:118:0)
[02:06:50.817] Timer tick, asking Buildbucket for the build status
[02:06:51.152] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:51.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:119:0) after 1m0s
[02:07:51.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:119:0)
[02:07:51.178] Timer tick, asking Buildbucket for the build status
[02:07:51.384] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:51.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:120:0) after 1m0s
[02:08:51.447] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:120:0)
[02:08:51.447] Timer tick, asking Buildbucket for the build status
[02:08:52.062] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:52.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:121:0) after 1m0s
[02:09:52.181] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:121:0)
[02:09:52.181] Timer tick, asking Buildbucket for the build status
[02:09:52.272] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:52.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:122:0) after 1m0s
[02:10:52.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:122:0)
[02:10:52.329] Timer tick, asking Buildbucket for the build status
[02:10:52.454] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:52.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:123:0) after 1m0s
[02:11:52.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:123:0)
[02:11:52.528] Timer tick, asking Buildbucket for the build status
[02:11:52.695] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:52.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:124:0) after 1m0s
[02:12:52.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:124:0)
[02:12:52.699] Timer tick, asking Buildbucket for the build status
[02:12:52.829] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:52.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:125:0) after 1m0s
[02:13:52.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:125:0)
[02:13:52.908] Timer tick, asking Buildbucket for the build status
[02:13:53.209] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:53.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:126:0) after 1m0s
[02:14:53.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:126:0)
[02:14:53.290] Timer tick, asking Buildbucket for the build status
[02:14:53.406] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:53.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:127:0) after 1m0s
[02:15:53.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:127:0)
[02:15:53.443] Timer tick, asking Buildbucket for the build status
[02:15:53.730] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:53.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:128:0) after 1m0s
[02:16:53.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:128:0)
[02:16:53.809] Timer tick, asking Buildbucket for the build status
[02:16:53.978] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:53.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:129:0) after 1m0s
[02:17:53.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:129:0)
[02:17:53.998] Timer tick, asking Buildbucket for the build status
[02:17:54.323] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:54.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:130:0) after 1m0s
[02:18:54.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:130:0)
[02:18:54.391] Timer tick, asking Buildbucket for the build status
[02:18:54.653] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:54.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:131:0) after 1m0s
[02:19:54.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:131:0)
[02:19:54.744] Timer tick, asking Buildbucket for the build status
[02:19:54.898] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:54.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:132:0) after 1m0s
[02:20:54.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:132:0)
[02:20:54.964] Timer tick, asking Buildbucket for the build status
[02:20:55.093] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:55.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:133:0) after 1m0s
[02:21:55.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:133:0)
[02:21:55.151] Timer tick, asking Buildbucket for the build status
[02:21:55.467] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:55.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:134:0) after 1m0s
[02:22:55.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:134:0)
[02:22:55.531] Timer tick, asking Buildbucket for the build status
[02:22:55.634] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:55.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:135:0) after 1m0s
[02:23:55.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:135:0)
[02:23:55.745] Timer tick, asking Buildbucket for the build status
[02:23:55.883] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:55.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:136:0) after 1m0s
[02:24:55.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:136:0)
[02:24:55.939] Timer tick, asking Buildbucket for the build status
[02:24:56.074] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:56.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:137:0) after 1m0s
[02:25:56.104] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:137:0)
[02:25:56.104] Timer tick, asking Buildbucket for the build status
[02:25:56.199] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:56.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:138:0) after 1m0s
[02:26:56.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:138:0)
[02:26:56.292] Timer tick, asking Buildbucket for the build status
[02:26:56.424] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:56.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:139:0) after 1m0s
[02:27:56.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:139:0)
[02:27:56.461] Timer tick, asking Buildbucket for the build status
[02:27:56.580] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:140:0) after 1m0s
[02:28:56.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:140:0)
[02:28:56.612] Timer tick, asking Buildbucket for the build status
[02:28:56.729] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:56.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:141:0) after 1m0s
[02:29:56.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:141:0)
[02:29:56.762] Timer tick, asking Buildbucket for the build status
[02:29:57.367] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:57.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:142:0) after 1m0s
[02:30:57.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:142:0)
[02:30:57.494] Timer tick, asking Buildbucket for the build status
[02:30:57.658] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:57.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:143:0) after 1m0s
[02:31:57.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:143:0)
[02:31:57.751] Timer tick, asking Buildbucket for the build status
[02:31:58.069] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:58.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:144:0) after 1m0s
[02:32:58.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:144:0)
[02:32:58.132] Timer tick, asking Buildbucket for the build status
[02:32:58.253] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:58.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:145:0) after 1m0s
[02:33:58.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:145:0)
[02:33:58.276] Timer tick, asking Buildbucket for the build status
[02:33:58.562] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:58.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:146:0) after 1m0s
[02:34:58.673] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:146:0)
[02:34:58.673] Timer tick, asking Buildbucket for the build status
[02:34:58.799] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:147:0) after 1m0s
[02:35:58.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:147:0)
[02:35:58.895] Timer tick, asking Buildbucket for the build status
[02:35:59.017] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:59.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:148:0) after 1m0s
[02:36:59.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:148:0)
[02:36:59.013] Timer tick, asking Buildbucket for the build status
[02:36:59.858] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:59.858] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:149:0) after 1m0s
[02:38:00.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:149:0)
[02:38:00.267] Timer tick, asking Buildbucket for the build status
[02:38:00.423] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:00.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:150:0) after 1m0s
[02:39:00.662] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:150:0)
[02:39:00.662] Timer tick, asking Buildbucket for the build status
[02:39:00.767] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:00.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:151:0) after 1m0s
[02:40:00.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:151:0)
[02:40:00.823] Timer tick, asking Buildbucket for the build status
[02:40:00.925] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:00.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:152:0) after 1m0s
[02:41:00.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:152:0)
[02:41:00.987] Timer tick, asking Buildbucket for the build status
[02:41:01.145] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:01.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:153:0) after 1m0s
[02:42:01.226] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:153:0)
[02:42:01.226] Timer tick, asking Buildbucket for the build status
[02:42:01.620] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:01.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:154:0) after 1m0s
[02:43:01.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:154:0)
[02:43:01.721] Timer tick, asking Buildbucket for the build status
[02:43:01.854] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:01.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:155:0) after 1m0s
[02:44:02.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:155:0)
[02:44:02.039] Timer tick, asking Buildbucket for the build status
[02:44:02.338] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:02.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:156:0) after 1m0s
[02:45:02.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:156:0)
[02:45:02.431] Timer tick, asking Buildbucket for the build status
[02:45:03.044] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:03.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:157:0) after 1m0s
[02:46:03.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:157:0)
[02:46:03.075] Timer tick, asking Buildbucket for the build status
[02:46:03.218] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:03.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:158:0) after 1m0s
[02:47:03.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:158:0)
[02:47:03.241] Timer tick, asking Buildbucket for the build status
[02:47:03.417] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:03.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:159:0) after 1m0s
[02:48:03.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:159:0)
[02:48:03.500] Timer tick, asking Buildbucket for the build status
[02:48:03.630] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:03.630] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:160:0) after 1m0s
[02:49:03.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:160:0)
[02:49:03.661] Timer tick, asking Buildbucket for the build status
[02:49:03.756] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:03.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:161:0) after 1m0s
[02:50:03.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:161:0)
[02:50:03.814] Timer tick, asking Buildbucket for the build status
[02:50:04.910] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:04.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:162:0) after 1m0s
[02:51:04.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:162:0)
[02:51:04.986] Timer tick, asking Buildbucket for the build status
[02:51:05.331] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:05.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:163:0) after 1m0s
[02:52:05.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:163:0)
[02:52:05.471] Timer tick, asking Buildbucket for the build status
[02:52:05.595] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:05.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:164:0) after 1m0s
[02:53:05.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:164:0)
[02:53:05.721] Timer tick, asking Buildbucket for the build status
[02:53:05.890] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:05.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:165:0) after 1m0s
[02:54:05.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:165:0)
[02:54:05.944] Timer tick, asking Buildbucket for the build status
[02:54:06.129] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:06.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:166:0) after 1m0s
[02:55:06.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:166:0)
[02:55:06.209] Timer tick, asking Buildbucket for the build status
[02:55:06.398] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:06.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:167:0) after 1m0s
[02:56:06.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:167:0)
[02:56:06.442] Timer tick, asking Buildbucket for the build status
[02:56:06.797] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:06.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:168:0) after 1m0s
[02:57:06.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:168:0)
[02:57:06.962] Timer tick, asking Buildbucket for the build status
[02:57:07.128] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:07.128] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:169:0) after 1m0s
[02:58:07.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:169:0)
[02:58:07.176] Timer tick, asking Buildbucket for the build status
[02:58:07.301] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:07.301] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:170:0) after 1m0s
[02:59:07.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:170:0)
[02:59:07.668] Timer tick, asking Buildbucket for the build status
[02:59:07.799] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:07.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:171:0) after 1m0s
[03:00:08.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:171:0)
[03:00:08.047] Timer tick, asking Buildbucket for the build status
[03:00:08.192] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:08.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:172:0) after 1m0s
[03:01:08.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:172:0)
[03:01:08.839] Timer tick, asking Buildbucket for the build status
[03:01:09.079] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:09.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:173:0) after 1m0s
[03:02:09.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:173:0)
[03:02:09.227] Timer tick, asking Buildbucket for the build status
[03:02:09.380] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:09.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:174:0) after 1m0s
[03:03:09.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:174:0)
[03:03:09.528] Timer tick, asking Buildbucket for the build status
[03:03:09.759] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:09.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:175:0) after 1m0s
[03:04:09.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:175:0)
[03:04:09.773] Timer tick, asking Buildbucket for the build status
[03:04:09.940] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:09.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:176:0) after 1m0s
[03:05:10.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:176:0)
[03:05:10.355] Timer tick, asking Buildbucket for the build status
[03:05:10.524] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:10.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:177:0) after 1m0s
[03:06:10.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:177:0)
[03:06:10.575] Timer tick, asking Buildbucket for the build status
[03:06:11.025] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:11.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:178:0) after 1m0s
[03:07:11.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:178:0)
[03:07:11.057] Timer tick, asking Buildbucket for the build status
[03:07:11.188] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:11.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:179:0) after 1m0s
[03:08:11.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:179:0)
[03:08:11.418] Timer tick, asking Buildbucket for the build status
[03:08:11.576] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:11.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:180:0) after 1m0s
[03:09:11.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:180:0)
[03:09:11.787] Timer tick, asking Buildbucket for the build status
[03:09:11.935] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:11.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:181:0) after 1m0s
[03:10:11.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:181:0)
[03:10:11.994] Timer tick, asking Buildbucket for the build status
[03:10:12.095] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:12.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:182:0) after 1m0s
[03:11:12.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:182:0)
[03:11:12.597] Timer tick, asking Buildbucket for the build status
[03:11:12.973] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:12.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:183:0) after 1m0s
[03:12:13.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:183:0)
[03:12:13.082] Timer tick, asking Buildbucket for the build status
[03:12:13.184] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:13.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:184:0) after 1m0s
[03:13:13.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:184:0)
[03:13:13.605] Timer tick, asking Buildbucket for the build status
[03:13:13.964] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:13.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:185:0) after 1m0s
[03:14:14.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:185:0)
[03:14:14.045] Timer tick, asking Buildbucket for the build status
[03:14:14.238] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:14.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:186:0) after 1m0s
[03:15:14.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:186:0)
[03:15:14.362] Timer tick, asking Buildbucket for the build status
[03:15:14.566] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:14.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:187:0) after 1m0s
[03:16:14.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:187:0)
[03:16:14.590] Timer tick, asking Buildbucket for the build status
[03:16:14.968] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:14.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:188:0) after 1m0s
[03:17:15.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:188:0)
[03:17:15.053] Timer tick, asking Buildbucket for the build status
[03:17:15.373] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:15.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:189:0) after 1m0s
[03:18:15.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:189:0)
[03:18:15.523] Timer tick, asking Buildbucket for the build status
[03:18:15.689] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:15.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:190:0) after 1m0s
[03:19:15.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:190:0)
[03:19:15.773] Timer tick, asking Buildbucket for the build status
[03:19:16.166] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:16.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:191:0) after 1m0s
[03:20:16.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:191:0)
[03:20:16.396] Timer tick, asking Buildbucket for the build status
[03:20:16.727] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:16.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:192:0) after 1m0s
[03:21:16.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:192:0)
[03:21:16.719] Timer tick, asking Buildbucket for the build status
[03:21:16.909] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:16.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:193:0) after 1m0s
[03:22:16.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:193:0)
[03:22:16.990] Timer tick, asking Buildbucket for the build status
[03:22:17.141] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:17.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:194:0) after 1m0s
[03:23:17.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:194:0)
[03:23:17.166] Timer tick, asking Buildbucket for the build status
[03:23:17.531] Build 8884991725850654032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:17.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050421788201830320:195:0) after 1m0s
[03:23:48.725] Received PubSub notification, asking Buildbucket for the build status
[03:23:48.789] Build 8884991725850654032: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:23:48.789] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1585020227812121",
    "created_by": "project:nacl",
    "created_ts": "1585008611782208",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884991725850654032",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"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\": \"55301a02435278dd3cb922da180df973df20ca70\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@55301a02435278dd3cb922da180df973df20ca70\", \"title\": \"55301a02435278dd3cb922da180df973df20ca70\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/55301a02435278dd3cb922da180df973df20ca70\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"55301a02435278dd3cb922da180df973df20ca70\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"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\": \"55301a02435278dd3cb922da180df973df20ca70\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@55301a02435278dd3cb922da180df973df20ca70\", \"title\": \"55301a02435278dd3cb922da180df973df20ca70\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/55301a02435278dd3cb922da180df973df20ca70\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-wmbs\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5146, \"got_revision\": \"55301a02435278dd3cb922da180df973df20ca70\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"55301a02435278dd3cb922da180df973df20ca70\", \"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-0-wmbs\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"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\": [\"4874-3598771\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585010148076483",
    "status": "COMPLETED",
    "status_changed_ts": "1585010148576819",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5146",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/55301a02435278dd3cb922da180df973df20ca70",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/55301a02435278dd3cb922da180df973df20ca70",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050421788201830320",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884991725850654032/+/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:4b22b1cd90a69510",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585020228251372",
    "url": "https://ci.chromium.org/b/8884991725850654032",
    "utcnow_ts": "1585020228782255"
  }
}
[03:23:48.789] Invocation finished in 3h13m38.633038452s with status FAILED