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

• 952e81813a66902714b68f34b51073c5d1ba6c94 (4 years ago)

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

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

[11:26:46.770] New invocation is queued and will start shortly
[11:26:47.852] Starting the invocation (attempt 1)
[11:26:47.915] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:26:47.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:26:47.917] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9021931772138887520",
  "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\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\",\"title\":\"952e81813a66902714b68f34b51073c5d1ba6c94\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
  "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:9021931772138887520",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
    "gitiles_ref:refs/heads/master"
  ]
}
[11:26:48.659] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:nacl",
    "created_ts": "1612178808046501",
    "id": "8856501710132964192",
    "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\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1612178808450656",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5123",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021931772138887520",
      "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/8856501710132964192/+/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": "1612178808450774",
    "url": "https://ci.chromium.org/b/8856501710132964192",
    "utcnow_ts": "1612178808651943"
  }
}
[11:26:48.659] Task URL: https://ci.chromium.org/b/8856501710132964192
[11:26:48.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:2:0) after 1m0s
[11:27:05.457] Received PubSub notification, asking Buildbucket for the build status
[11:27:05.556] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:48.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:2:0)
[11:27:48.790] Timer tick, asking Buildbucket for the build status
[11:27:48.888] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:48.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:4:0) after 1m0s
[11:28:48.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:4:0)
[11:28:48.884] Timer tick, asking Buildbucket for the build status
[11:28:49.205] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:49.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:5:0) after 1m0s
[11:29:49.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:5:0)
[11:29:49.245] Timer tick, asking Buildbucket for the build status
[11:29:49.337] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:49.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:6:0) after 1m0s
[11:30:49.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:6:0)
[11:30:49.444] Timer tick, asking Buildbucket for the build status
[11:30:49.736] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:49.736] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:7:0) after 1m0s
[11:31:49.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:7:0)
[11:31:49.729] Timer tick, asking Buildbucket for the build status
[11:31:50.004] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:50.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:8:0) after 1m0s
[11:32:50.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:8:0)
[11:32:50.044] Timer tick, asking Buildbucket for the build status
[11:32:50.155] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:50.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:9:0) after 1m0s
[11:33:50.179] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:9:0)
[11:33:50.179] Timer tick, asking Buildbucket for the build status
[11:33:50.626] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:50.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:10:0) after 1m0s
[11:34:50.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:10:0)
[11:34:50.681] Timer tick, asking Buildbucket for the build status
[11:34:50.826] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:11:0) after 1m0s
[11:35:50.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:11:0)
[11:35:50.921] Timer tick, asking Buildbucket for the build status
[11:35:51.140] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:51.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:12:0) after 1m0s
[11:36:51.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:12:0)
[11:36:51.175] Timer tick, asking Buildbucket for the build status
[11:36:51.614] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:51.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:13:0) after 1m0s
[11:37:51.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:13:0)
[11:37:51.651] Timer tick, asking Buildbucket for the build status
[11:37:51.815] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:51.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:14:0) after 1m0s
[11:38:51.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:14:0)
[11:38:51.869] Timer tick, asking Buildbucket for the build status
[11:38:51.929] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:51.929] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:15:0) after 1m0s
[11:39:52.036] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:15:0)
[11:39:52.036] Timer tick, asking Buildbucket for the build status
[11:39:52.152] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:52.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:16:0) after 1m0s
[11:40:52.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:16:0)
[11:40:52.232] Timer tick, asking Buildbucket for the build status
[11:40:52.432] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:52.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:17:0) after 1m0s
[11:41:52.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:17:0)
[11:41:52.475] Timer tick, asking Buildbucket for the build status
[11:41:52.596] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:52.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:18:0) after 1m0s
[11:42:52.627] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:18:0)
[11:42:52.627] Timer tick, asking Buildbucket for the build status
[11:42:52.693] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:52.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:19:0) after 1m0s
[11:43:52.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:19:0)
[11:43:52.711] Timer tick, asking Buildbucket for the build status
[11:43:52.856] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:52.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:20:0) after 1m0s
[11:44:52.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:20:0)
[11:44:52.909] Timer tick, asking Buildbucket for the build status
[11:44:53.024] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:53.024] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:21:0) after 1m0s
[11:45:53.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:21:0)
[11:45:53.046] Timer tick, asking Buildbucket for the build status
[11:45:53.322] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:53.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:22:0) after 1m0s
[11:46:53.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:22:0)
[11:46:53.379] Timer tick, asking Buildbucket for the build status
[11:46:53.746] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:53.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:23:0) after 1m0s
[11:47:53.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:23:0)
[11:47:53.817] Timer tick, asking Buildbucket for the build status
[11:47:53.930] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:53.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:24:0) after 1m0s
[11:48:53.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:24:0)
[11:48:53.954] Timer tick, asking Buildbucket for the build status
[11:48:54.158] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:54.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:25:0) after 1m0s
[11:49:54.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:25:0)
[11:49:54.217] Timer tick, asking Buildbucket for the build status
[11:49:54.357] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:54.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:26:0) after 1m0s
[11:50:54.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:26:0)
[11:50:54.403] Timer tick, asking Buildbucket for the build status
[11:50:54.930] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:54.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:27:0) after 1m0s
[11:51:54.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:27:0)
[11:51:54.967] Timer tick, asking Buildbucket for the build status
[11:51:55.211] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:55.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:28:0) after 1m0s
[11:52:55.255] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:28:0)
[11:52:55.255] Timer tick, asking Buildbucket for the build status
[11:52:55.355] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:55.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:29:0) after 1m0s
[11:53:55.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:29:0)
[11:53:55.425] Timer tick, asking Buildbucket for the build status
[11:53:55.582] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:55.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:30:0) after 1m0s
[11:54:55.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:30:0)
[11:54:55.603] Timer tick, asking Buildbucket for the build status
[11:54:56.188] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:56.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:31:0) after 1m0s
[11:55:56.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:31:0)
[11:55:56.173] Timer tick, asking Buildbucket for the build status
[11:55:56.224] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:56.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:32:0) after 1m0s
[11:56:56.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:32:0)
[11:56:56.264] Timer tick, asking Buildbucket for the build status
[11:56:56.430] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:56.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:33:0) after 1m0s
[11:57:56.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:33:0)
[11:57:56.456] Timer tick, asking Buildbucket for the build status
[11:57:56.526] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:56.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:34:0) after 1m0s
[11:58:56.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:34:0)
[11:58:56.594] Timer tick, asking Buildbucket for the build status
[11:58:56.833] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:56.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:35:0) after 1m0s
[11:59:56.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:35:0)
[11:59:56.952] Timer tick, asking Buildbucket for the build status
[11:59:57.021] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:57.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:36:0) after 1m0s
[12:00:57.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:36:0)
[12:00:57.134] Timer tick, asking Buildbucket for the build status
[12:00:57.229] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:57.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:37:0) after 1m0s
[12:01:57.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:37:0)
[12:01:57.305] Timer tick, asking Buildbucket for the build status
[12:01:57.550] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:57.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:38:0) after 1m0s
[12:02:57.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:38:0)
[12:02:57.579] Timer tick, asking Buildbucket for the build status
[12:02:58.040] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:58.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:39:0) after 1m0s
[12:03:58.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:39:0)
[12:03:58.071] Timer tick, asking Buildbucket for the build status
[12:03:58.338] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:58.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:40:0) after 1m0s
[12:04:58.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:40:0)
[12:04:58.356] Timer tick, asking Buildbucket for the build status
[12:04:58.787] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:58.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:41:0) after 1m0s
[12:05:58.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:41:0)
[12:05:58.937] Timer tick, asking Buildbucket for the build status
[12:05:58.995] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:58.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:42:0) after 1m0s
[12:06:59.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:42:0)
[12:06:59.166] Timer tick, asking Buildbucket for the build status
[12:06:59.280] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:59.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:43:0) after 1m0s
[12:07:59.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:43:0)
[12:07:59.389] Timer tick, asking Buildbucket for the build status
[12:07:59.649] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:59.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:44:0) after 1m0s
[12:08:59.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:44:0)
[12:08:59.699] Timer tick, asking Buildbucket for the build status
[12:08:59.934] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:59.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:45:0) after 1m0s
[12:09:59.995] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:45:0)
[12:09:59.995] Timer tick, asking Buildbucket for the build status
[12:10:00.191] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:00.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:46:0) after 1m0s
[12:11:00.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:46:0)
[12:11:00.286] Timer tick, asking Buildbucket for the build status
[12:11:00.386] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:00.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:47:0) after 1m0s
[12:12:00.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:47:0)
[12:12:00.853] Timer tick, asking Buildbucket for the build status
[12:12:01.403] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:01.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:48:0) after 1m0s
[12:13:01.591] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:48:0)
[12:13:01.591] Timer tick, asking Buildbucket for the build status
[12:13:01.842] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:01.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:49:0) after 1m0s
[12:14:01.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:49:0)
[12:14:01.967] Timer tick, asking Buildbucket for the build status
[12:14:02.255] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:02.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:50:0) after 1m0s
[12:15:02.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:50:0)
[12:15:02.356] Timer tick, asking Buildbucket for the build status
[12:15:02.807] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:02.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:51:0) after 1m0s
[12:16:02.933] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:51:0)
[12:16:02.933] Timer tick, asking Buildbucket for the build status
[12:16:03.031] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:03.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:52:0) after 1m0s
[12:17:03.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:52:0)
[12:17:03.142] Timer tick, asking Buildbucket for the build status
[12:17:03.251] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:03.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:53:0) after 1m0s
[12:18:03.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:53:0)
[12:18:03.275] Timer tick, asking Buildbucket for the build status
[12:18:03.393] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:03.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:54:0) after 1m0s
[12:19:03.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:54:0)
[12:19:03.421] Timer tick, asking Buildbucket for the build status
[12:19:03.540] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:03.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:55:0) after 1m0s
[12:20:03.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:55:0)
[12:20:03.602] Timer tick, asking Buildbucket for the build status
[12:20:03.794] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:03.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:56:0) after 1m0s
[12:21:03.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:56:0)
[12:21:03.817] Timer tick, asking Buildbucket for the build status
[12:21:04.248] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:04.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:57:0) after 1m0s
[12:22:04.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:57:0)
[12:22:04.292] Timer tick, asking Buildbucket for the build status
[12:22:04.639] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:04.639] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:58:0) after 1m0s
[12:23:04.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:58:0)
[12:23:04.698] Timer tick, asking Buildbucket for the build status
[12:23:04.759] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:04.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:59:0) after 1m0s
[12:24:04.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:59:0)
[12:24:04.780] Timer tick, asking Buildbucket for the build status
[12:24:04.900] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:04.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:60:0) after 1m0s
[12:25:04.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:60:0)
[12:25:04.965] Timer tick, asking Buildbucket for the build status
[12:25:05.066] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:05.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:61:0) after 1m0s
[12:26:05.126] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:61:0)
[12:26:05.126] Timer tick, asking Buildbucket for the build status
[12:26:05.187] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:05.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:62:0) after 1m0s
[12:27:05.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:62:0)
[12:27:05.216] Timer tick, asking Buildbucket for the build status
[12:27:05.370] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:05.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:63:0) after 1m0s
[12:28:05.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:63:0)
[12:28:05.473] Timer tick, asking Buildbucket for the build status
[12:28:05.753] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:05.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:64:0) after 1m0s
[12:29:05.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:64:0)
[12:29:05.799] Timer tick, asking Buildbucket for the build status
[12:29:05.864] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:05.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:65:0) after 1m0s
[12:30:05.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:65:0)
[12:30:05.981] Timer tick, asking Buildbucket for the build status
[12:30:06.223] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:06.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:66:0) after 1m0s
[12:31:06.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:66:0)
[12:31:06.274] Timer tick, asking Buildbucket for the build status
[12:31:06.349] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:06.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:67:0) after 1m0s
[12:32:06.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:67:0)
[12:32:06.576] Timer tick, asking Buildbucket for the build status
[12:32:06.701] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:06.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:68:0) after 1m0s
[12:33:06.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:68:0)
[12:33:06.767] Timer tick, asking Buildbucket for the build status
[12:33:07.021] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:07.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:69:0) after 1m0s
[12:34:07.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:69:0)
[12:34:07.051] Timer tick, asking Buildbucket for the build status
[12:34:07.124] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:07.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:70:0) after 1m0s
[12:35:07.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:70:0)
[12:35:07.236] Timer tick, asking Buildbucket for the build status
[12:35:07.392] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:07.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:71:0) after 1m0s
[12:36:07.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:71:0)
[12:36:07.457] Timer tick, asking Buildbucket for the build status
[12:36:07.525] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:07.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:72:0) after 1m0s
[12:37:07.545] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:72:0)
[12:37:07.545] Timer tick, asking Buildbucket for the build status
[12:37:07.782] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:07.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:73:0) after 1m0s
[12:38:07.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:73:0)
[12:38:07.891] Timer tick, asking Buildbucket for the build status
[12:38:08.114] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:08.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:74:0) after 1m0s
[12:39:08.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:74:0)
[12:39:08.149] Timer tick, asking Buildbucket for the build status
[12:39:08.381] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:08.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:75:0) after 1m0s
[12:40:08.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:75:0)
[12:40:08.445] Timer tick, asking Buildbucket for the build status
[12:40:08.519] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:08.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:76:0) after 1m0s
[12:41:08.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:76:0)
[12:41:08.551] Timer tick, asking Buildbucket for the build status
[12:41:08.619] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:08.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:77:0) after 1m0s
[12:42:08.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:77:0)
[12:42:08.677] Timer tick, asking Buildbucket for the build status
[12:42:08.790] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:08.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:78:0) after 1m0s
[12:43:08.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:78:0)
[12:43:08.812] Timer tick, asking Buildbucket for the build status
[12:43:08.886] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:08.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:79:0) after 1m0s
[12:44:08.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:79:0)
[12:44:08.907] Timer tick, asking Buildbucket for the build status
[12:44:08.992] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:08.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:80:0) after 1m0s
[12:45:09.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:80:0)
[12:45:09.030] Timer tick, asking Buildbucket for the build status
[12:45:09.187] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:09.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:81:0) after 1m0s
[12:46:09.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:81:0)
[12:46:09.218] Timer tick, asking Buildbucket for the build status
[12:46:09.282] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:09.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:82:0) after 1m0s
[12:47:09.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:82:0)
[12:47:09.319] Timer tick, asking Buildbucket for the build status
[12:47:09.514] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:09.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:83:0) after 1m0s
[12:48:09.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:83:0)
[12:48:09.682] Timer tick, asking Buildbucket for the build status
[12:48:10.015] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:10.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:84:0) after 1m0s
[12:49:10.070] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:84:0)
[12:49:10.070] Timer tick, asking Buildbucket for the build status
[12:49:10.563] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:10.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:85:0) after 1m0s
[12:50:10.649] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:85:0)
[12:50:10.649] Timer tick, asking Buildbucket for the build status
[12:50:11.048] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:11.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:86:0) after 1m0s
[12:51:11.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:86:0)
[12:51:11.098] Timer tick, asking Buildbucket for the build status
[12:51:11.202] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:11.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:87:0) after 1m0s
[12:52:11.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:87:0)
[12:52:11.503] Timer tick, asking Buildbucket for the build status
[12:52:11.954] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:11.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:88:0) after 1m0s
[12:53:12.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:88:0)
[12:53:12.267] Timer tick, asking Buildbucket for the build status
[12:53:12.364] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:12.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:89:0) after 1m0s
[12:54:12.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:89:0)
[12:54:12.532] Timer tick, asking Buildbucket for the build status
[12:54:12.675] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:12.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:90:0) after 1m0s
[12:55:12.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:90:0)
[12:55:12.758] Timer tick, asking Buildbucket for the build status
[12:55:13.303] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:13.303] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:91:0) after 1m0s
[12:56:13.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:91:0)
[12:56:13.389] Timer tick, asking Buildbucket for the build status
[12:56:13.453] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:13.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:92:0) after 1m0s
[12:57:13.520] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:92:0)
[12:57:13.520] Timer tick, asking Buildbucket for the build status
[12:57:13.659] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:13.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:93:0) after 1m0s
[12:58:13.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:93:0)
[12:58:13.939] Timer tick, asking Buildbucket for the build status
[12:58:13.999] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:13.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:94:0) after 1m0s
[12:59:14.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:94:0)
[12:59:14.163] Timer tick, asking Buildbucket for the build status
[12:59:14.233] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:14.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:95:0) after 1m0s
[13:00:14.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:95:0)
[13:00:14.411] Timer tick, asking Buildbucket for the build status
[13:00:14.830] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:14.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:96:0) after 1m0s
[13:01:14.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:96:0)
[13:01:14.842] Timer tick, asking Buildbucket for the build status
[13:01:14.986] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:14.986] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:97:0) after 1m0s
[13:02:15.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:97:0)
[13:02:15.059] Timer tick, asking Buildbucket for the build status
[13:02:15.168] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:15.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:98:0) after 1m0s
[13:03:15.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:98:0)
[13:03:15.262] Timer tick, asking Buildbucket for the build status
[13:03:15.363] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:15.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:99:0) after 1m0s
[13:04:15.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:99:0)
[13:04:15.413] Timer tick, asking Buildbucket for the build status
[13:04:15.604] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:15.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:100:0) after 1m0s
[13:05:15.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:100:0)
[13:05:15.645] Timer tick, asking Buildbucket for the build status
[13:05:15.712] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:15.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:101:0) after 1m0s
[13:06:15.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:101:0)
[13:06:15.797] Timer tick, asking Buildbucket for the build status
[13:06:16.123] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:16.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:102:0) after 1m0s
[13:07:16.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:102:0)
[13:07:16.237] Timer tick, asking Buildbucket for the build status
[13:07:16.608] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:16.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:103:0) after 1m0s
[13:08:16.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:103:0)
[13:08:16.624] Timer tick, asking Buildbucket for the build status
[13:08:16.740] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:16.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:104:0) after 1m0s
[13:09:16.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:104:0)
[13:09:16.808] Timer tick, asking Buildbucket for the build status
[13:09:17.113] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:17.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:105:0) after 1m0s
[13:10:17.180] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:105:0)
[13:10:17.180] Timer tick, asking Buildbucket for the build status
[13:10:17.300] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:17.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:106:0) after 1m0s
[13:11:17.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:106:0)
[13:11:17.341] Timer tick, asking Buildbucket for the build status
[13:11:17.803] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:17.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:107:0) after 1m0s
[13:12:17.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:107:0)
[13:12:17.886] Timer tick, asking Buildbucket for the build status
[13:12:18.021] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:18.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:108:0) after 1m0s
[13:13:18.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:108:0)
[13:13:18.071] Timer tick, asking Buildbucket for the build status
[13:13:18.244] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:18.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:109:0) after 1m0s
[13:14:18.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:109:0)
[13:14:18.281] Timer tick, asking Buildbucket for the build status
[13:14:18.793] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:18.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:110:0) after 1m0s
[13:15:19.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:110:0)
[13:15:19.197] Timer tick, asking Buildbucket for the build status
[13:15:19.827] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:19.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:111:0) after 1m0s
[13:16:19.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:111:0)
[13:16:19.941] Timer tick, asking Buildbucket for the build status
[13:16:20.013] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:20.013] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:112:0) after 1m0s
[13:17:20.256] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:112:0)
[13:17:20.256] Timer tick, asking Buildbucket for the build status
[13:17:20.477] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:20.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:113:0) after 1m0s
[13:18:20.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:113:0)
[13:18:20.585] Timer tick, asking Buildbucket for the build status
[13:18:20.654] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:20.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:114:0) after 1m0s
[13:19:20.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:114:0)
[13:19:20.892] Timer tick, asking Buildbucket for the build status
[13:19:20.957] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:20.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:115:0) after 1m0s
[13:20:21.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:115:0)
[13:20:21.243] Timer tick, asking Buildbucket for the build status
[13:20:21.311] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:21.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:116:0) after 1m0s
[13:21:21.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:116:0)
[13:21:21.562] Timer tick, asking Buildbucket for the build status
[13:21:21.621] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:21.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:117:0) after 1m0s
[13:22:21.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:117:0)
[13:22:21.828] Timer tick, asking Buildbucket for the build status
[13:22:21.945] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:21.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:118:0) after 1m0s
[13:23:23.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:118:0)
[13:23:23.945] Timer tick, asking Buildbucket for the build status
[13:23:24.017] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:24.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:119:0) after 1m0s
[13:24:24.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:119:0)
[13:24:24.065] Timer tick, asking Buildbucket for the build status
[13:24:24.132] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:24.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:120:0) after 1m0s
[13:25:24.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:120:0)
[13:25:24.243] Timer tick, asking Buildbucket for the build status
[13:25:24.329] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:24.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:121:0) after 1m0s
[13:26:24.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:121:0)
[13:26:24.453] Timer tick, asking Buildbucket for the build status
[13:26:24.537] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:24.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:122:0) after 1m0s
[13:27:24.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:122:0)
[13:27:24.635] Timer tick, asking Buildbucket for the build status
[13:27:24.739] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:24.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:123:0) after 1m0s
[13:28:24.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:123:0)
[13:28:24.828] Timer tick, asking Buildbucket for the build status
[13:28:24.898] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:24.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:124:0) after 1m0s
[13:29:24.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:124:0)
[13:29:24.950] Timer tick, asking Buildbucket for the build status
[13:29:25.017] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:25.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:125:0) after 1m0s
[13:30:25.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:125:0)
[13:30:25.106] Timer tick, asking Buildbucket for the build status
[13:30:25.186] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:25.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:126:0) after 1m0s
[13:31:25.214] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:126:0)
[13:31:25.214] Timer tick, asking Buildbucket for the build status
[13:31:25.268] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:25.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:127:0) after 1m0s
[13:32:25.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:127:0)
[13:32:25.325] Timer tick, asking Buildbucket for the build status
[13:32:25.723] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:25.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:128:0) after 1m0s
[13:33:25.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:128:0)
[13:33:25.799] Timer tick, asking Buildbucket for the build status
[13:33:25.876] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:25.876] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:129:0) after 1m0s
[13:34:25.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:129:0)
[13:34:25.905] Timer tick, asking Buildbucket for the build status
[13:34:26.034] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:130:0) after 1m0s
[13:35:26.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:130:0)
[13:35:26.129] Timer tick, asking Buildbucket for the build status
[13:35:26.200] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:26.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:131:0) after 1m0s
[13:36:26.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:131:0)
[13:36:26.321] Timer tick, asking Buildbucket for the build status
[13:36:26.379] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:26.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:132:0) after 1m0s
[13:37:26.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:132:0)
[13:37:26.423] Timer tick, asking Buildbucket for the build status
[13:37:26.842] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:26.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:133:0) after 1m0s
[13:38:26.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:133:0)
[13:38:26.964] Timer tick, asking Buildbucket for the build status
[13:38:27.044] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:134:0) after 1m0s
[13:39:27.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:134:0)
[13:39:27.173] Timer tick, asking Buildbucket for the build status
[13:39:27.500] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:135:0) after 1m0s
[13:40:27.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:135:0)
[13:40:27.599] Timer tick, asking Buildbucket for the build status
[13:40:27.661] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:27.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:136:0) after 1m0s
[13:41:27.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:136:0)
[13:41:27.688] Timer tick, asking Buildbucket for the build status
[13:41:27.749] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:27.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:137:0) after 1m0s
[13:42:27.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:137:0)
[13:42:27.831] Timer tick, asking Buildbucket for the build status
[13:42:27.972] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:27.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:138:0) after 1m0s
[13:43:28.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:138:0)
[13:43:28.042] Timer tick, asking Buildbucket for the build status
[13:43:28.124] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:28.124] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:139:0) after 1m0s
[13:44:28.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:139:0)
[13:44:28.232] Timer tick, asking Buildbucket for the build status
[13:44:28.306] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:28.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:140:0) after 1m0s
[13:45:28.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:140:0)
[13:45:28.387] Timer tick, asking Buildbucket for the build status
[13:45:28.458] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:28.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:141:0) after 1m0s
[13:46:28.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:141:0)
[13:46:28.547] Timer tick, asking Buildbucket for the build status
[13:46:29.025] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:29.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:142:0) after 1m0s
[13:47:29.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:142:0)
[13:47:29.082] Timer tick, asking Buildbucket for the build status
[13:47:29.311] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:29.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:143:0) after 1m0s
[13:48:29.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:143:0)
[13:48:29.371] Timer tick, asking Buildbucket for the build status
[13:48:29.628] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:29.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:144:0) after 1m0s
[13:49:29.678] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:144:0)
[13:49:29.678] Timer tick, asking Buildbucket for the build status
[13:49:29.833] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:29.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:145:0) after 1m0s
[13:50:29.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:145:0)
[13:50:29.923] Timer tick, asking Buildbucket for the build status
[13:50:30.022] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:30.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:146:0) after 1m0s
[13:51:30.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:146:0)
[13:51:30.090] Timer tick, asking Buildbucket for the build status
[13:51:30.161] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:30.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:147:0) after 1m0s
[13:52:30.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:147:0)
[13:52:30.210] Timer tick, asking Buildbucket for the build status
[13:52:30.325] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:30.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:148:0) after 1m0s
[13:53:30.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:148:0)
[13:53:30.467] Timer tick, asking Buildbucket for the build status
[13:53:30.532] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:30.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:149:0) after 1m0s
[13:54:30.571] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:149:0)
[13:54:30.571] Timer tick, asking Buildbucket for the build status
[13:54:30.826] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:30.826] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:150:0) after 1m0s
[13:55:30.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:150:0)
[13:55:30.854] Timer tick, asking Buildbucket for the build status
[13:55:31.158] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:31.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:151:0) after 1m0s
[13:56:31.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:151:0)
[13:56:31.258] Timer tick, asking Buildbucket for the build status
[13:56:31.332] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:31.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:152:0) after 1m0s
[13:57:31.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:152:0)
[13:57:31.356] Timer tick, asking Buildbucket for the build status
[13:57:31.958] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:31.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:153:0) after 1m0s
[13:58:32.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:153:0)
[13:58:32.054] Timer tick, asking Buildbucket for the build status
[13:58:32.136] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:32.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:154:0) after 1m0s
[13:59:32.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:154:0)
[13:59:32.153] Timer tick, asking Buildbucket for the build status
[13:59:32.235] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:32.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:155:0) after 1m0s
[14:00:32.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:155:0)
[14:00:32.264] Timer tick, asking Buildbucket for the build status
[14:00:32.429] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:32.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:156:0) after 1m0s
[14:01:32.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:156:0)
[14:01:32.436] Timer tick, asking Buildbucket for the build status
[14:01:32.493] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:32.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:157:0) after 1m0s
[14:02:32.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:157:0)
[14:02:32.593] Timer tick, asking Buildbucket for the build status
[14:02:32.708] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:32.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:158:0) after 1m0s
[14:03:32.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:158:0)
[14:03:32.812] Timer tick, asking Buildbucket for the build status
[14:03:33.458] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:33.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:159:0) after 1m0s
[14:04:33.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:159:0)
[14:04:33.494] Timer tick, asking Buildbucket for the build status
[14:04:33.573] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:33.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:160:0) after 1m0s
[14:05:33.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:160:0)
[14:05:33.610] Timer tick, asking Buildbucket for the build status
[14:05:33.706] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:33.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:161:0) after 1m0s
[14:06:33.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:161:0)
[14:06:33.859] Timer tick, asking Buildbucket for the build status
[14:06:34.031] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:34.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:162:0) after 1m0s
[14:07:34.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:162:0)
[14:07:34.101] Timer tick, asking Buildbucket for the build status
[14:07:34.233] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:34.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:163:0) after 1m0s
[14:08:34.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:163:0)
[14:08:34.269] Timer tick, asking Buildbucket for the build status
[14:08:34.349] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:34.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:164:0) after 1m0s
[14:09:34.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:164:0)
[14:09:34.393] Timer tick, asking Buildbucket for the build status
[14:09:34.650] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:34.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:165:0) after 1m0s
[14:10:34.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:165:0)
[14:10:34.689] Timer tick, asking Buildbucket for the build status
[14:10:34.806] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:34.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:166:0) after 1m0s
[14:11:34.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:166:0)
[14:11:34.850] Timer tick, asking Buildbucket for the build status
[14:11:34.911] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:34.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:167:0) after 1m0s
[14:12:35.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:167:0)
[14:12:35.177] Timer tick, asking Buildbucket for the build status
[14:12:35.282] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:35.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:168:0) after 1m0s
[14:13:35.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:168:0)
[14:13:35.338] Timer tick, asking Buildbucket for the build status
[14:13:35.561] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:35.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:169:0) after 1m0s
[14:14:35.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:169:0)
[14:14:35.633] Timer tick, asking Buildbucket for the build status
[14:14:35.698] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:35.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:170:0) after 1m0s
[14:15:35.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:170:0)
[14:15:35.768] Timer tick, asking Buildbucket for the build status
[14:15:35.845] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:35.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:171:0) after 1m0s
[14:16:35.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:171:0)
[14:16:35.948] Timer tick, asking Buildbucket for the build status
[14:16:36.135] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:36.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:172:0) after 1m0s
[14:17:36.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:172:0)
[14:17:36.186] Timer tick, asking Buildbucket for the build status
[14:17:36.504] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:36.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:173:0) after 1m0s
[14:18:36.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:173:0)
[14:18:36.557] Timer tick, asking Buildbucket for the build status
[14:18:36.664] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:36.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:174:0) after 1m0s
[14:19:36.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:174:0)
[14:19:36.717] Timer tick, asking Buildbucket for the build status
[14:19:36.807] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:36.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:175:0) after 1m0s
[14:20:36.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:175:0)
[14:20:36.970] Timer tick, asking Buildbucket for the build status
[14:20:37.068] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:37.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:176:0) after 1m0s
[14:21:37.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:176:0)
[14:21:37.105] Timer tick, asking Buildbucket for the build status
[14:21:37.255] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:37.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:177:0) after 1m0s
[14:22:37.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:177:0)
[14:22:37.276] Timer tick, asking Buildbucket for the build status
[14:22:37.809] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:37.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:178:0) after 1m0s
[14:23:37.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:178:0)
[14:23:37.805] Timer tick, asking Buildbucket for the build status
[14:23:38.154] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:38.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:179:0) after 1m0s
[14:24:38.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:179:0)
[14:24:38.183] Timer tick, asking Buildbucket for the build status
[14:24:38.405] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:38.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:180:0) after 1m0s
[14:25:38.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:180:0)
[14:25:38.510] Timer tick, asking Buildbucket for the build status
[14:25:38.588] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:38.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:181:0) after 1m0s
[14:26:38.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:181:0)
[14:26:38.647] Timer tick, asking Buildbucket for the build status
[14:26:39.005] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:39.005] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:182:0) after 1m0s
[14:27:39.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:182:0)
[14:27:39.034] Timer tick, asking Buildbucket for the build status
[14:27:39.545] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:39.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:183:0) after 1m0s
[14:28:39.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:183:0)
[14:28:39.639] Timer tick, asking Buildbucket for the build status
[14:28:40.076] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:40.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:184:0) after 1m0s
[14:29:40.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:184:0)
[14:29:40.125] Timer tick, asking Buildbucket for the build status
[14:29:40.194] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:40.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:185:0) after 1m0s
[14:30:40.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:185:0)
[14:30:40.279] Timer tick, asking Buildbucket for the build status
[14:30:40.368] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:40.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:186:0) after 1m0s
[14:31:40.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:186:0)
[14:31:40.450] Timer tick, asking Buildbucket for the build status
[14:31:40.691] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:40.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:187:0) after 1m0s
[14:32:40.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:187:0)
[14:32:40.731] Timer tick, asking Buildbucket for the build status
[14:32:41.102] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:41.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:188:0) after 1m0s
[14:33:41.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:188:0)
[14:33:41.148] Timer tick, asking Buildbucket for the build status
[14:33:41.283] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:41.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:189:0) after 1m0s
[14:34:41.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:189:0)
[14:34:41.311] Timer tick, asking Buildbucket for the build status
[14:34:42.092] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:42.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:190:0) after 1m0s
[14:35:42.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:190:0)
[14:35:42.118] Timer tick, asking Buildbucket for the build status
[14:35:42.251] Build 8856501710132964192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021931772138887520:191:0) after 1m0s
[14:36:35.647] Received PubSub notification, asking Buildbucket for the build status
[14:36:35.945] Build 8856501710132964192: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:36:35.945] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1612190194890539",
    "created_by": "project:nacl",
    "created_ts": "1612178808046501",
    "failure_reason": "BUILD_FAILURE",
    "id": "8856501710132964192",
    "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\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}}",
    "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\": \"952e81813a66902714b68f34b51073c5d1ba6c94\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@952e81813a66902714b68f34b51073c5d1ba6c94\", \"title\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/952e81813a66902714b68f34b51073c5d1ba6c94\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-ht90\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5123, \"got_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-ht90\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5673-2413400\"], \"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": "1612178824551122",
    "status": "COMPLETED",
    "status_changed_ts": "1612178825099747",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5123",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/952e81813a66902714b68f34b51073c5d1ba6c94",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/952e81813a66902714b68f34b51073c5d1ba6c94",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021931772138887520",
      "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/8856501710132964192/+/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:517629e8fdc53710",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1612190195094617",
    "url": "https://ci.chromium.org/b/8856501710132964192",
    "utcnow_ts": "1612190195920130"
  }
}
[14:36:35.945] Invocation finished in 3h9m49.19660415s with status FAILED