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

• 3008f9e6de122325d8f9dbf02f7cdd51fa1ec306 (4 years ago)

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

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

[17:45:55.745] New invocation is queued and will start shortly
[17:46:06.134] Starting the invocation (attempt 1)
[17:46:06.189] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:46:06.189] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:46:06.189] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9039030744754455680",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"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\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\",\"title\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_64",
    "scheduler_invocation_id:9039030744754455680",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
    "gitiles_ref:refs/heads/master"
  ]
}
[17:46:08.304] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1595871967017802",
    "id": "8873600672271353760",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"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\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1595871968022592",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5113",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9039030744754455680",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8873600672271353760/+/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": "1595871968022765",
    "url": "https://ci.chromium.org/b/8873600672271353760",
    "utcnow_ts": "1595871968286044"
  }
}
[17:46:08.304] Task URL: https://ci.chromium.org/b/8873600672271353760
[17:46:08.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:2:0) after 1m0s
[17:47:09.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:2:0)
[17:47:09.433] Timer tick, asking Buildbucket for the build status
[17:47:09.567] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:09.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:3:0) after 1m0s
[17:48:09.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:3:0)
[17:48:09.685] Timer tick, asking Buildbucket for the build status
[17:48:09.795] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:09.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:4:0) after 1m0s
[17:49:09.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:4:0)
[17:49:09.831] Timer tick, asking Buildbucket for the build status
[17:49:10.008] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:10.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:5:0) after 1m0s
[17:50:10.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:5:0)
[17:50:10.177] Timer tick, asking Buildbucket for the build status
[17:50:10.373] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:10.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:6:0) after 1m0s
[17:51:10.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:6:0)
[17:51:10.467] Timer tick, asking Buildbucket for the build status
[17:51:11.732] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:51:11.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:7:0) after 1m0s
[17:52:11.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:7:0)
[17:52:11.904] Timer tick, asking Buildbucket for the build status
[17:52:12.456] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:52:12.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:8:0) after 1m0s
[17:53:12.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:8:0)
[17:53:12.897] Timer tick, asking Buildbucket for the build status
[17:53:13.004] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:13.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:9:0) after 1m0s
[17:54:13.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:9:0)
[17:54:13.395] Timer tick, asking Buildbucket for the build status
[17:54:13.484] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:13.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:10:0) after 1m0s
[17:55:13.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:10:0)
[17:55:13.651] Timer tick, asking Buildbucket for the build status
[17:55:13.741] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:13.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:11:0) after 1m0s
[17:56:14.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:11:0)
[17:56:14.019] Timer tick, asking Buildbucket for the build status
[17:56:14.183] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:14.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:12:0) after 1m0s
[17:57:14.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:12:0)
[17:57:14.271] Timer tick, asking Buildbucket for the build status
[17:57:14.433] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:14.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:13:0) after 1m0s
[17:58:14.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:13:0)
[17:58:14.578] Timer tick, asking Buildbucket for the build status
[17:58:14.805] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:58:14.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:14:0) after 1m0s
[17:59:15.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:14:0)
[17:59:15.068] Timer tick, asking Buildbucket for the build status
[17:59:15.255] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:15.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:15:0) after 1m0s
[18:00:15.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:15:0)
[18:00:15.443] Timer tick, asking Buildbucket for the build status
[18:00:15.998] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:15.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:16:0) after 1m0s
[18:01:16.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:16:0)
[18:01:16.241] Timer tick, asking Buildbucket for the build status
[18:01:16.520] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:16.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:17:0) after 1m0s
[18:02:16.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:17:0)
[18:02:16.548] Timer tick, asking Buildbucket for the build status
[18:02:17.156] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:17.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:18:0) after 1m0s
[18:03:17.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:18:0)
[18:03:17.186] Timer tick, asking Buildbucket for the build status
[18:03:17.368] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:17.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:19:0) after 1m0s
[18:04:17.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:19:0)
[18:04:17.436] Timer tick, asking Buildbucket for the build status
[18:04:17.578] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:17.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:20:0) after 1m0s
[18:05:17.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:20:0)
[18:05:17.628] Timer tick, asking Buildbucket for the build status
[18:05:18.418] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:18.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:21:0) after 1m0s
[18:06:18.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:21:0)
[18:06:18.417] Timer tick, asking Buildbucket for the build status
[18:06:18.583] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:18.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:22:0) after 1m0s
[18:07:18.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:22:0)
[18:07:18.633] Timer tick, asking Buildbucket for the build status
[18:07:19.727] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:19.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:23:0) after 1m0s
[18:08:19.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:23:0)
[18:08:19.763] Timer tick, asking Buildbucket for the build status
[18:08:19.842] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:19.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:24:0) after 1m0s
[18:09:19.877] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:24:0)
[18:09:19.877] Timer tick, asking Buildbucket for the build status
[18:09:20.199] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:20.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:25:0) after 1m0s
[18:10:20.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:25:0)
[18:10:20.262] Timer tick, asking Buildbucket for the build status
[18:10:20.383] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:20.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:26:0) after 1m0s
[18:11:20.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:26:0)
[18:11:20.410] Timer tick, asking Buildbucket for the build status
[18:11:20.608] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:20.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:27:0) after 1m0s
[18:12:20.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:27:0)
[18:12:20.867] Timer tick, asking Buildbucket for the build status
[18:12:21.045] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:21.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:28:0) after 1m0s
[18:13:21.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:28:0)
[18:13:21.393] Timer tick, asking Buildbucket for the build status
[18:13:21.655] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:21.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:29:0) after 1m0s
[18:14:21.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:29:0)
[18:14:21.975] Timer tick, asking Buildbucket for the build status
[18:14:22.086] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:22.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:30:0) after 1m0s
[18:15:22.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:30:0)
[18:15:22.178] Timer tick, asking Buildbucket for the build status
[18:15:22.951] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:15:22.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:31:0) after 1m0s
[18:16:22.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:31:0)
[18:16:22.942] Timer tick, asking Buildbucket for the build status
[18:16:23.187] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:16:23.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:32:0) after 1m0s
[18:17:23.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:32:0)
[18:17:23.335] Timer tick, asking Buildbucket for the build status
[18:17:23.643] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:23.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:33:0) after 1m0s
[18:18:23.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:33:0)
[18:18:23.788] Timer tick, asking Buildbucket for the build status
[18:18:23.974] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:23.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:34:0) after 1m0s
[18:19:24.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:34:0)
[18:19:24.328] Timer tick, asking Buildbucket for the build status
[18:19:24.584] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:19:24.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:35:0) after 1m0s
[18:20:24.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:35:0)
[18:20:24.811] Timer tick, asking Buildbucket for the build status
[18:20:25.171] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:20:25.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:36:0) after 1m0s
[18:21:25.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:36:0)
[18:21:25.222] Timer tick, asking Buildbucket for the build status
[18:21:25.433] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:21:25.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:37:0) after 1m0s
[18:22:25.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:37:0)
[18:22:25.539] Timer tick, asking Buildbucket for the build status
[18:22:25.696] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:22:25.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:38:0) after 1m0s
[18:23:25.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:38:0)
[18:23:25.771] Timer tick, asking Buildbucket for the build status
[18:23:25.938] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:23:25.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:39:0) after 1m0s
[18:24:26.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:39:0)
[18:24:26.017] Timer tick, asking Buildbucket for the build status
[18:24:26.290] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:26.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:40:0) after 1m0s
[18:25:26.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:40:0)
[18:25:26.366] Timer tick, asking Buildbucket for the build status
[18:25:26.475] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:26.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:41:0) after 1m0s
[18:26:26.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:41:0)
[18:26:26.595] Timer tick, asking Buildbucket for the build status
[18:26:27.353] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:27.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:42:0) after 1m0s
[18:27:27.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:42:0)
[18:27:27.433] Timer tick, asking Buildbucket for the build status
[18:27:27.556] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:27.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:43:0) after 1m0s
[18:28:27.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:43:0)
[18:28:27.653] Timer tick, asking Buildbucket for the build status
[18:28:27.761] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:27.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:44:0) after 1m0s
[18:29:28.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:44:0)
[18:29:28.014] Timer tick, asking Buildbucket for the build status
[18:29:28.138] Build 8873600672271353760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:28.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:45:0) after 1m0s
[18:30:24.336] Received PubSub notification, asking Buildbucket for the build status
[18:30:24.448] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:28.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:45:0)
[18:30:28.280] Timer tick, asking Buildbucket for the build status
[18:30:28.373] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:28.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:47:0) after 1m0s
[18:31:28.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:47:0)
[18:31:28.536] Timer tick, asking Buildbucket for the build status
[18:31:29.232] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:29.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:48:0) after 1m0s
[18:32:29.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:48:0)
[18:32:29.299] Timer tick, asking Buildbucket for the build status
[18:32:29.875] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:29.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:49:0) after 1m0s
[18:33:29.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:49:0)
[18:33:29.917] Timer tick, asking Buildbucket for the build status
[18:33:30.187] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:30.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:50:0) after 1m0s
[18:34:30.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:50:0)
[18:34:30.256] Timer tick, asking Buildbucket for the build status
[18:34:30.450] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:30.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:51:0) after 1m0s
[18:35:30.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:51:0)
[18:35:30.468] Timer tick, asking Buildbucket for the build status
[18:35:31.551] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:31.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:52:0) after 1m0s
[18:36:31.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:52:0)
[18:36:31.626] Timer tick, asking Buildbucket for the build status
[18:36:32.068] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:32.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:53:0) after 1m0s
[18:37:32.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:53:0)
[18:37:32.180] Timer tick, asking Buildbucket for the build status
[18:37:32.646] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:32.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:54:0) after 1m0s
[18:38:32.724] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:54:0)
[18:38:32.724] Timer tick, asking Buildbucket for the build status
[18:38:32.795] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:32.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:55:0) after 1m0s
[18:39:32.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:55:0)
[18:39:32.875] Timer tick, asking Buildbucket for the build status
[18:39:33.417] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:33.417] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:56:0) after 1m0s
[18:40:33.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:56:0)
[18:40:33.458] Timer tick, asking Buildbucket for the build status
[18:40:33.557] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:33.557] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:57:0) after 1m0s
[18:41:33.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:57:0)
[18:41:33.579] Timer tick, asking Buildbucket for the build status
[18:41:34.432] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:34.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:58:0) after 1m0s
[18:42:34.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:58:0)
[18:42:34.483] Timer tick, asking Buildbucket for the build status
[18:42:34.726] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:34.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:59:0) after 1m0s
[18:43:34.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:59:0)
[18:43:34.802] Timer tick, asking Buildbucket for the build status
[18:43:34.930] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:34.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:60:0) after 1m0s
[18:44:34.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:60:0)
[18:44:34.981] Timer tick, asking Buildbucket for the build status
[18:44:35.278] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:35.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:61:0) after 1m0s
[18:45:35.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:61:0)
[18:45:35.284] Timer tick, asking Buildbucket for the build status
[18:45:36.658] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:36.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:62:0) after 1m0s
[18:46:36.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:62:0)
[18:46:36.714] Timer tick, asking Buildbucket for the build status
[18:46:37.321] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:37.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:63:0) after 1m0s
[18:47:37.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:63:0)
[18:47:37.368] Timer tick, asking Buildbucket for the build status
[18:47:37.997] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:37.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:64:0) after 1m0s
[18:48:38.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:64:0)
[18:48:38.048] Timer tick, asking Buildbucket for the build status
[18:48:39.544] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:39.544] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:65:0) after 1m0s
[18:49:39.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:65:0)
[18:49:39.616] Timer tick, asking Buildbucket for the build status
[18:49:40.281] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:40.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:66:0) after 1m0s
[18:50:40.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:66:0)
[18:50:40.415] Timer tick, asking Buildbucket for the build status
[18:50:41.847] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:41.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:67:0) after 1m0s
[18:51:41.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:67:0)
[18:51:41.936] Timer tick, asking Buildbucket for the build status
[18:51:42.705] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:42.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:68:0) after 1m0s
[18:52:42.803] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:68:0)
[18:52:42.803] Timer tick, asking Buildbucket for the build status
[18:52:42.992] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:42.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:69:0) after 1m0s
[18:53:43.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:69:0)
[18:53:43.032] Timer tick, asking Buildbucket for the build status
[18:53:43.616] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:43.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:70:0) after 1m0s
[18:54:43.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:70:0)
[18:54:43.672] Timer tick, asking Buildbucket for the build status
[18:54:43.852] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:43.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:71:0) after 1m0s
[18:55:43.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:71:0)
[18:55:43.964] Timer tick, asking Buildbucket for the build status
[18:55:44.161] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:44.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:72:0) after 1m0s
[18:56:44.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:72:0)
[18:56:44.272] Timer tick, asking Buildbucket for the build status
[18:56:44.467] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:44.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:73:0) after 1m0s
[18:57:44.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:73:0)
[18:57:44.609] Timer tick, asking Buildbucket for the build status
[18:57:44.984] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:44.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:74:0) after 1m0s
[18:58:45.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:74:0)
[18:58:45.038] Timer tick, asking Buildbucket for the build status
[18:58:45.847] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:45.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:75:0) after 1m0s
[18:59:45.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:75:0)
[18:59:45.878] Timer tick, asking Buildbucket for the build status
[18:59:46.042] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:46.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:76:0) after 1m0s
[19:00:46.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:76:0)
[19:00:46.111] Timer tick, asking Buildbucket for the build status
[19:00:46.303] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:46.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:77:0) after 1m0s
[19:01:46.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:77:0)
[19:01:46.391] Timer tick, asking Buildbucket for the build status
[19:01:46.988] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:46.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:78:0) after 1m0s
[19:02:47.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:78:0)
[19:02:47.035] Timer tick, asking Buildbucket for the build status
[19:02:47.310] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:47.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:79:0) after 1m0s
[19:03:47.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:79:0)
[19:03:47.350] Timer tick, asking Buildbucket for the build status
[19:03:48.851] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:48.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:80:0) after 1m0s
[19:04:48.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:80:0)
[19:04:48.886] Timer tick, asking Buildbucket for the build status
[19:04:51.702] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:51.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:81:0) after 1m0s
[19:05:51.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:81:0)
[19:05:51.767] Timer tick, asking Buildbucket for the build status
[19:05:51.931] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:51.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:82:0) after 1m0s
[19:06:51.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:82:0)
[19:06:51.983] Timer tick, asking Buildbucket for the build status
[19:06:52.217] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:52.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:83:0) after 1m0s
[19:07:52.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:83:0)
[19:07:52.318] Timer tick, asking Buildbucket for the build status
[19:07:52.583] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:52.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:84:0) after 1m0s
[19:08:52.632] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:84:0)
[19:08:52.632] Timer tick, asking Buildbucket for the build status
[19:08:52.752] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:52.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:85:0) after 1m0s
[19:09:52.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:85:0)
[19:09:52.777] Timer tick, asking Buildbucket for the build status
[19:09:53.455] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:53.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:86:0) after 1m0s
[19:10:53.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:86:0)
[19:10:53.585] Timer tick, asking Buildbucket for the build status
[19:10:53.718] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:53.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:87:0) after 1m0s
[19:11:53.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:87:0)
[19:11:53.801] Timer tick, asking Buildbucket for the build status
[19:11:54.008] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:54.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:88:0) after 1m0s
[19:12:54.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:88:0)
[19:12:54.052] Timer tick, asking Buildbucket for the build status
[19:12:54.162] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:54.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:89:0) after 1m0s
[19:13:54.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:89:0)
[19:13:54.191] Timer tick, asking Buildbucket for the build status
[19:13:54.268] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:54.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:90:0) after 1m0s
[19:14:54.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:90:0)
[19:14:54.307] Timer tick, asking Buildbucket for the build status
[19:14:54.436] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:54.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:91:0) after 1m0s
[19:15:54.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:91:0)
[19:15:54.465] Timer tick, asking Buildbucket for the build status
[19:15:54.915] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:54.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:92:0) after 1m0s
[19:16:55.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:92:0)
[19:16:55.018] Timer tick, asking Buildbucket for the build status
[19:16:55.275] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:55.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:93:0) after 1m0s
[19:17:55.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:93:0)
[19:17:55.321] Timer tick, asking Buildbucket for the build status
[19:17:55.755] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:55.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:94:0) after 1m0s
[19:18:55.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:94:0)
[19:18:55.890] Timer tick, asking Buildbucket for the build status
[19:18:56.020] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:56.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:95:0) after 1m0s
[19:19:56.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:95:0)
[19:19:56.173] Timer tick, asking Buildbucket for the build status
[19:19:56.283] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:56.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:96:0) after 1m0s
[19:20:56.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:96:0)
[19:20:56.393] Timer tick, asking Buildbucket for the build status
[19:20:56.819] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:56.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:97:0) after 1m0s
[19:21:56.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:97:0)
[19:21:56.931] Timer tick, asking Buildbucket for the build status
[19:21:57.925] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:57.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:98:0) after 1m0s
[19:22:58.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:98:0)
[19:22:58.014] Timer tick, asking Buildbucket for the build status
[19:22:58.133] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:58.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:99:0) after 1m0s
[19:23:58.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:99:0)
[19:23:58.248] Timer tick, asking Buildbucket for the build status
[19:23:58.351] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:58.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:100:0) after 1m0s
[19:24:58.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:100:0)
[19:24:58.594] Timer tick, asking Buildbucket for the build status
[19:24:58.675] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:58.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:101:0) after 1m0s
[19:25:58.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:101:0)
[19:25:58.747] Timer tick, asking Buildbucket for the build status
[19:25:58.951] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:58.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:102:0) after 1m0s
[19:26:59.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:102:0)
[19:26:59.103] Timer tick, asking Buildbucket for the build status
[19:26:59.423] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:59.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:103:0) after 1m0s
[19:27:59.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:103:0)
[19:27:59.525] Timer tick, asking Buildbucket for the build status
[19:27:59.620] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:59.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:104:0) after 1m0s
[19:28:59.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:104:0)
[19:28:59.766] Timer tick, asking Buildbucket for the build status
[19:28:59.887] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:59.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:105:0) after 1m0s
[19:29:59.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:105:0)
[19:29:59.968] Timer tick, asking Buildbucket for the build status
[19:30:00.112] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:00.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:106:0) after 1m0s
[19:31:00.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:106:0)
[19:31:00.155] Timer tick, asking Buildbucket for the build status
[19:31:00.509] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:00.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:107:0) after 1m0s
[19:32:00.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:107:0)
[19:32:00.555] Timer tick, asking Buildbucket for the build status
[19:32:00.722] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:00.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:108:0) after 1m0s
[19:33:01.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:108:0)
[19:33:01.317] Timer tick, asking Buildbucket for the build status
[19:33:01.639] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:01.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:109:0) after 1m0s
[19:34:01.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:109:0)
[19:34:01.623] Timer tick, asking Buildbucket for the build status
[19:34:02.808] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:02.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:110:0) after 1m0s
[19:35:02.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:110:0)
[19:35:02.851] Timer tick, asking Buildbucket for the build status
[19:35:02.986] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:02.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:111:0) after 1m0s
[19:36:02.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:111:0)
[19:36:02.999] Timer tick, asking Buildbucket for the build status
[19:36:03.103] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:03.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:112:0) after 1m0s
[19:37:03.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:112:0)
[19:37:03.155] Timer tick, asking Buildbucket for the build status
[19:37:03.394] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:03.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:113:0) after 1m0s
[19:38:03.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:113:0)
[19:38:03.429] Timer tick, asking Buildbucket for the build status
[19:38:03.805] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:03.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:114:0) after 1m0s
[19:39:03.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:114:0)
[19:39:03.878] Timer tick, asking Buildbucket for the build status
[19:39:05.373] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:05.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:115:0) after 1m0s
[19:40:05.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:115:0)
[19:40:05.393] Timer tick, asking Buildbucket for the build status
[19:40:05.682] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:05.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:116:0) after 1m0s
[19:41:05.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:116:0)
[19:41:05.813] Timer tick, asking Buildbucket for the build status
[19:41:05.871] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:05.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:117:0) after 1m0s
[19:42:05.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:117:0)
[19:42:05.899] Timer tick, asking Buildbucket for the build status
[19:42:06.119] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:06.119] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:118:0) after 1m0s
[19:43:06.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:118:0)
[19:43:06.199] Timer tick, asking Buildbucket for the build status
[19:43:06.377] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:06.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:119:0) after 1m0s
[19:44:06.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:119:0)
[19:44:06.473] Timer tick, asking Buildbucket for the build status
[19:44:06.635] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:06.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:120:0) after 1m0s
[19:45:06.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:120:0)
[19:45:06.878] Timer tick, asking Buildbucket for the build status
[19:45:07.180] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:07.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:121:0) after 1m0s
[19:46:07.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:121:0)
[19:46:07.252] Timer tick, asking Buildbucket for the build status
[19:46:10.236] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:10.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:122:0) after 1m0s
[19:47:10.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:122:0)
[19:47:10.446] Timer tick, asking Buildbucket for the build status
[19:47:10.742] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:10.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:123:0) after 1m0s
[19:48:10.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:123:0)
[19:48:10.826] Timer tick, asking Buildbucket for the build status
[19:48:11.135] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:11.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:124:0) after 1m0s
[19:49:11.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:124:0)
[19:49:11.373] Timer tick, asking Buildbucket for the build status
[19:49:11.499] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:11.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:125:0) after 1m0s
[19:50:11.512] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:125:0)
[19:50:11.512] Timer tick, asking Buildbucket for the build status
[19:50:16.647] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:16.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:126:0) after 1m0s
[19:51:16.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:126:0)
[19:51:16.680] Timer tick, asking Buildbucket for the build status
[19:51:17.136] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:17.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:127:0) after 1m0s
[19:52:17.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:127:0)
[19:52:17.199] Timer tick, asking Buildbucket for the build status
[19:52:17.317] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:17.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:128:0) after 1m0s
[19:53:17.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:128:0)
[19:53:17.387] Timer tick, asking Buildbucket for the build status
[19:53:17.719] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:17.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:129:0) after 1m0s
[19:54:17.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:129:0)
[19:54:17.752] Timer tick, asking Buildbucket for the build status
[19:54:20.033] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:20.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:130:0) after 1m0s
[19:55:20.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:130:0)
[19:55:20.083] Timer tick, asking Buildbucket for the build status
[19:55:20.906] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:20.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:131:0) after 1m0s
[19:56:21.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:131:0)
[19:56:21.023] Timer tick, asking Buildbucket for the build status
[19:56:21.196] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:21.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:132:0) after 1m0s
[19:57:21.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:132:0)
[19:57:21.228] Timer tick, asking Buildbucket for the build status
[19:57:21.426] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:21.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:133:0) after 1m0s
[19:58:21.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:133:0)
[19:58:21.492] Timer tick, asking Buildbucket for the build status
[19:58:21.584] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:21.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:134:0) after 1m0s
[19:59:21.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:134:0)
[19:59:21.677] Timer tick, asking Buildbucket for the build status
[19:59:21.810] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:21.810] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:135:0) after 1m0s
[20:00:21.918] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:135:0)
[20:00:21.918] Timer tick, asking Buildbucket for the build status
[20:00:23.053] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:23.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:136:0) after 1m0s
[20:01:23.139] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:136:0)
[20:01:23.139] Timer tick, asking Buildbucket for the build status
[20:01:23.351] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:23.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:137:0) after 1m0s
[20:02:23.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:137:0)
[20:02:23.409] Timer tick, asking Buildbucket for the build status
[20:02:23.718] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:23.718] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:138:0) after 1m0s
[20:03:23.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:138:0)
[20:03:23.782] Timer tick, asking Buildbucket for the build status
[20:03:23.981] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:23.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:139:0) after 1m0s
[20:04:24.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:139:0)
[20:04:24.067] Timer tick, asking Buildbucket for the build status
[20:04:24.268] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:24.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:140:0) after 1m0s
[20:05:24.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:140:0)
[20:05:24.305] Timer tick, asking Buildbucket for the build status
[20:05:24.427] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:24.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:141:0) after 1m0s
[20:06:24.459] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:141:0)
[20:06:24.459] Timer tick, asking Buildbucket for the build status
[20:06:24.714] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:24.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:142:0) after 1m0s
[20:07:24.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:142:0)
[20:07:24.747] Timer tick, asking Buildbucket for the build status
[20:07:29.378] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:29.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:143:0) after 1m0s
[20:08:29.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:143:0)
[20:08:29.498] Timer tick, asking Buildbucket for the build status
[20:08:30.436] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:144:0) after 1m0s
[20:09:30.480] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:144:0)
[20:09:30.480] Timer tick, asking Buildbucket for the build status
[20:09:30.621] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:30.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:145:0) after 1m0s
[20:10:30.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:145:0)
[20:10:30.726] Timer tick, asking Buildbucket for the build status
[20:10:30.821] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:30.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:146:0) after 1m0s
[20:11:30.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:146:0)
[20:11:30.857] Timer tick, asking Buildbucket for the build status
[20:11:31.636] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:31.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:147:0) after 1m0s
[20:12:31.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:147:0)
[20:12:31.725] Timer tick, asking Buildbucket for the build status
[20:12:32.028] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:32.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:148:0) after 1m0s
[20:13:32.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:148:0)
[20:13:32.106] Timer tick, asking Buildbucket for the build status
[20:13:32.296] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:32.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:149:0) after 1m0s
[20:14:32.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:149:0)
[20:14:32.330] Timer tick, asking Buildbucket for the build status
[20:14:32.495] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:32.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:150:0) after 1m0s
[20:15:32.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:150:0)
[20:15:32.530] Timer tick, asking Buildbucket for the build status
[20:15:32.617] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:32.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:151:0) after 1m0s
[20:16:32.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:151:0)
[20:16:32.648] Timer tick, asking Buildbucket for the build status
[20:16:33.273] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:33.273] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:152:0) after 1m0s
[20:17:33.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:152:0)
[20:17:33.298] Timer tick, asking Buildbucket for the build status
[20:17:33.400] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:33.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:153:0) after 1m0s
[20:18:33.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:153:0)
[20:18:33.427] Timer tick, asking Buildbucket for the build status
[20:18:33.713] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:33.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:154:0) after 1m0s
[20:19:33.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:154:0)
[20:19:33.754] Timer tick, asking Buildbucket for the build status
[20:19:33.973] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:33.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:155:0) after 1m0s
[20:20:34.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:155:0)
[20:20:34.009] Timer tick, asking Buildbucket for the build status
[20:20:34.145] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:34.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:156:0) after 1m0s
[20:21:34.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:156:0)
[20:21:34.198] Timer tick, asking Buildbucket for the build status
[20:21:34.536] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:157:0) after 1m0s
[20:22:34.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:157:0)
[20:22:34.661] Timer tick, asking Buildbucket for the build status
[20:22:35.108] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:35.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:158:0) after 1m0s
[20:23:35.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:158:0)
[20:23:35.151] Timer tick, asking Buildbucket for the build status
[20:23:35.276] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:35.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:159:0) after 1m0s
[20:24:35.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:159:0)
[20:24:35.364] Timer tick, asking Buildbucket for the build status
[20:24:35.479] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:35.480] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:160:0) after 1m0s
[20:25:35.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:160:0)
[20:25:35.503] Timer tick, asking Buildbucket for the build status
[20:25:35.640] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:35.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:161:0) after 1m0s
[20:26:35.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:161:0)
[20:26:35.771] Timer tick, asking Buildbucket for the build status
[20:26:36.125] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:36.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:162:0) after 1m0s
[20:27:36.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:162:0)
[20:27:36.166] Timer tick, asking Buildbucket for the build status
[20:27:36.280] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:36.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:163:0) after 1m0s
[20:28:36.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:163:0)
[20:28:36.319] Timer tick, asking Buildbucket for the build status
[20:28:36.572] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:36.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:164:0) after 1m0s
[20:29:36.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:164:0)
[20:29:36.601] Timer tick, asking Buildbucket for the build status
[20:29:36.934] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:36.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:165:0) after 1m0s
[20:30:36.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:165:0)
[20:30:36.946] Timer tick, asking Buildbucket for the build status
[20:30:37.132] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:37.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:166:0) after 1m0s
[20:31:37.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:166:0)
[20:31:37.246] Timer tick, asking Buildbucket for the build status
[20:31:37.643] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:37.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:167:0) after 1m0s
[20:32:37.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:167:0)
[20:32:37.781] Timer tick, asking Buildbucket for the build status
[20:32:37.858] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:37.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:168:0) after 1m0s
[20:33:38.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:168:0)
[20:33:38.142] Timer tick, asking Buildbucket for the build status
[20:33:38.264] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:38.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:169:0) after 1m0s
[20:34:38.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:169:0)
[20:34:38.401] Timer tick, asking Buildbucket for the build status
[20:34:38.502] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:38.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:170:0) after 1m0s
[20:35:38.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:170:0)
[20:35:38.606] Timer tick, asking Buildbucket for the build status
[20:35:39.042] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:39.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:171:0) after 1m0s
[20:36:39.080] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:171:0)
[20:36:39.080] Timer tick, asking Buildbucket for the build status
[20:36:39.238] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:39.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:172:0) after 1m0s
[20:37:39.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:172:0)
[20:37:39.383] Timer tick, asking Buildbucket for the build status
[20:37:39.489] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:39.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:173:0) after 1m0s
[20:38:39.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:173:0)
[20:38:39.551] Timer tick, asking Buildbucket for the build status
[20:38:39.654] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:39.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:174:0) after 1m0s
[20:39:39.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:174:0)
[20:39:39.719] Timer tick, asking Buildbucket for the build status
[20:39:39.846] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:39.846] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:175:0) after 1m0s
[20:40:39.845] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:175:0)
[20:40:39.845] Timer tick, asking Buildbucket for the build status
[20:40:40.022] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:40.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:176:0) after 1m0s
[20:41:40.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:176:0)
[20:41:40.092] Timer tick, asking Buildbucket for the build status
[20:41:40.344] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:40.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:177:0) after 1m0s
[20:42:40.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:177:0)
[20:42:40.418] Timer tick, asking Buildbucket for the build status
[20:42:40.878] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:40.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:178:0) after 1m0s
[20:43:40.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:178:0)
[20:43:40.931] Timer tick, asking Buildbucket for the build status
[20:43:41.488] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:41.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:179:0) after 1m0s
[20:44:41.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:179:0)
[20:44:41.523] Timer tick, asking Buildbucket for the build status
[20:44:41.627] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:41.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:180:0) after 1m0s
[20:45:41.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:180:0)
[20:45:41.697] Timer tick, asking Buildbucket for the build status
[20:45:42.019] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:42.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:181:0) after 1m0s
[20:46:42.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:181:0)
[20:46:42.111] Timer tick, asking Buildbucket for the build status
[20:46:42.585] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:42.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:182:0) after 1m0s
[20:47:42.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:182:0)
[20:47:42.631] Timer tick, asking Buildbucket for the build status
[20:47:42.838] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:42.838] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:183:0) after 1m0s
[20:48:42.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:183:0)
[20:48:42.892] Timer tick, asking Buildbucket for the build status
[20:48:43.165] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:43.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:184:0) after 1m0s
[20:49:43.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:184:0)
[20:49:43.208] Timer tick, asking Buildbucket for the build status
[20:49:43.876] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:43.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:185:0) after 1m0s
[20:50:43.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:185:0)
[20:50:43.938] Timer tick, asking Buildbucket for the build status
[20:50:44.064] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:44.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:186:0) after 1m0s
[20:51:44.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:186:0)
[20:51:44.095] Timer tick, asking Buildbucket for the build status
[20:51:44.304] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:44.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:187:0) after 1m0s
[20:52:44.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:187:0)
[20:52:44.381] Timer tick, asking Buildbucket for the build status
[20:52:44.556] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:44.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:188:0) after 1m0s
[20:53:44.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:188:0)
[20:53:44.707] Timer tick, asking Buildbucket for the build status
[20:53:44.807] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:44.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:189:0) after 1m0s
[20:54:44.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:189:0)
[20:54:44.976] Timer tick, asking Buildbucket for the build status
[20:54:45.100] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:45.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:190:0) after 1m0s
[20:55:45.170] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:190:0)
[20:55:45.170] Timer tick, asking Buildbucket for the build status
[20:55:45.990] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:45.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:191:0) after 1m0s
[20:56:46.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:191:0)
[20:56:46.094] Timer tick, asking Buildbucket for the build status
[20:56:46.277] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:46.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:192:0) after 1m0s
[20:57:46.450] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:192:0)
[20:57:46.450] Timer tick, asking Buildbucket for the build status
[20:57:46.582] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:46.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:193:0) after 1m0s
[20:58:46.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:193:0)
[20:58:46.671] Timer tick, asking Buildbucket for the build status
[20:58:46.879] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:46.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:194:0) after 1m0s
[20:59:46.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:194:0)
[20:59:46.895] Timer tick, asking Buildbucket for the build status
[20:59:47.463] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:47.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:195:0) after 1m0s
[21:00:47.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:195:0)
[21:00:47.491] Timer tick, asking Buildbucket for the build status
[21:00:47.605] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:47.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:196:0) after 1m0s
[21:01:47.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:196:0)
[21:01:47.678] Timer tick, asking Buildbucket for the build status
[21:01:47.868] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:47.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:197:0) after 1m0s
[21:02:47.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:197:0)
[21:02:47.914] Timer tick, asking Buildbucket for the build status
[21:02:48.427] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:48.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:198:0) after 1m0s
[21:03:48.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:198:0)
[21:03:48.510] Timer tick, asking Buildbucket for the build status
[21:03:48.881] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:48.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:199:0) after 1m0s
[21:04:48.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:199:0)
[21:04:48.938] Timer tick, asking Buildbucket for the build status
[21:04:49.143] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:49.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:200:0) after 1m0s
[21:05:49.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:200:0)
[21:05:49.215] Timer tick, asking Buildbucket for the build status
[21:05:49.327] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:49.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:201:0) after 1m0s
[21:06:50.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:201:0)
[21:06:50.446] Timer tick, asking Buildbucket for the build status
[21:06:50.603] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:50.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:202:0) after 1m0s
[21:07:50.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:202:0)
[21:07:50.903] Timer tick, asking Buildbucket for the build status
[21:07:50.999] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:50.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:203:0) after 1m0s
[21:08:51.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:203:0)
[21:08:51.227] Timer tick, asking Buildbucket for the build status
[21:08:51.413] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:51.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:204:0) after 1m0s
[21:09:51.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:204:0)
[21:09:51.439] Timer tick, asking Buildbucket for the build status
[21:09:51.529] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:51.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:205:0) after 1m0s
[21:10:51.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:205:0)
[21:10:51.717] Timer tick, asking Buildbucket for the build status
[21:10:51.910] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:51.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:206:0) after 1m0s
[21:11:51.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:206:0)
[21:11:51.973] Timer tick, asking Buildbucket for the build status
[21:11:52.161] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:52.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:207:0) after 1m0s
[21:12:52.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:207:0)
[21:12:52.354] Timer tick, asking Buildbucket for the build status
[21:12:52.534] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:52.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:208:0) after 1m0s
[21:13:52.630] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:208:0)
[21:13:52.630] Timer tick, asking Buildbucket for the build status
[21:13:52.848] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:52.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:209:0) after 1m0s
[21:14:52.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:209:0)
[21:14:52.913] Timer tick, asking Buildbucket for the build status
[21:14:53.218] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:53.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:210:0) after 1m0s
[21:15:53.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:210:0)
[21:15:53.254] Timer tick, asking Buildbucket for the build status
[21:15:53.607] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:53.607] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:211:0) after 1m0s
[21:16:53.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:211:0)
[21:16:53.681] Timer tick, asking Buildbucket for the build status
[21:16:53.899] Build 8873600672271353760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:53.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9039030744754455680:212:0) after 1m0s
[21:17:45.895] Received PubSub notification, asking Buildbucket for the build status
[21:17:46.928] Build 8873600672271353760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:17:46.928] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1595884665490925",
    "created_by": "project:nacl",
    "created_ts": "1595871967017802",
    "failure_reason": "BUILD_FAILURE",
    "id": "8873600672271353760",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"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\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-1-dwhm\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5113, \"got_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"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-1-dwhm\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5434-62f16a1\"], \"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": "1595874623667467",
    "status": "COMPLETED",
    "status_changed_ts": "1595874624057961",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5113",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9039030744754455680",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8873600672271353760/+/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:4daa33443a78e010",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1595884665589111",
    "url": "https://ci.chromium.org/b/8873600672271353760",
    "utcnow_ts": "1595884666896091"
  }
}
[21:17:46.928] Invocation finished in 3h31m51.19896686s with status FAILED