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

• 3008f9e6de122325d8f9dbf02f7cdd51fa1ec306 (4 years ago)

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

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

[17:45:54.301] New invocation is queued and will start shortly
[17:46:02.122] Starting the invocation (attempt 1)
[17:46:02.208] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:46:02.219] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:46:02.219] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9039030746276068768",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-arm\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\",\"title\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-arm",
    "scheduler_invocation_id:9039030746276068768",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
    "user_agent:luci-scheduler",
    "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
    "gitiles_ref:refs/heads/master"
  ]
}
[17:46:04.227] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1595871963437416",
    "id": "8873600676025368816",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1595871963999731",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4745",
      "builder:linux-pnacl-x86_64-tests-arm",
      "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9039030746276068768",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8873600676025368816/+/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": "1595871963999919",
    "url": "https://ci.chromium.org/b/8873600676025368816",
    "utcnow_ts": "1595871964213315"
  }
}
[17:46:04.227] Task URL: https://ci.chromium.org/b/8873600676025368816
[17:46:04.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:2:0) after 1m0s
[17:47:00.584] Received PubSub notification, asking Buildbucket for the build status
[17:47:00.694] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:04.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:2:0)
[17:47:04.243] Timer tick, asking Buildbucket for the build status
[17:47:04.465] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:04.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:4:0) after 1m0s
[17:48:04.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:4:0)
[17:48:04.608] Timer tick, asking Buildbucket for the build status
[17:48:04.817] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:04.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:5:0) after 1m0s
[17:49:04.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:5:0)
[17:49:04.905] Timer tick, asking Buildbucket for the build status
[17:49:05.118] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:05.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:6:0) after 1m0s
[17:50:05.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:6:0)
[17:50:05.192] Timer tick, asking Buildbucket for the build status
[17:50:05.344] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:05.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:7:0) after 1m0s
[17:51:05.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:7:0)
[17:51:05.482] Timer tick, asking Buildbucket for the build status
[17:51:06.315] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:06.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:8:0) after 1m0s
[17:52:06.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:8:0)
[17:52:06.412] Timer tick, asking Buildbucket for the build status
[17:52:06.521] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:06.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:9:0) after 1m0s
[17:53:06.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:9:0)
[17:53:06.621] Timer tick, asking Buildbucket for the build status
[17:53:06.922] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:06.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:10:0) after 1m0s
[17:54:06.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:10:0)
[17:54:06.952] Timer tick, asking Buildbucket for the build status
[17:54:07.271] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:07.271] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:11:0) after 1m0s
[17:55:07.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:11:0)
[17:55:07.446] Timer tick, asking Buildbucket for the build status
[17:55:07.619] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:07.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:12:0) after 1m0s
[17:56:07.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:12:0)
[17:56:07.694] Timer tick, asking Buildbucket for the build status
[17:56:07.884] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:07.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:13:0) after 1m0s
[17:57:07.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:13:0)
[17:57:07.987] Timer tick, asking Buildbucket for the build status
[17:57:08.106] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:08.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:14:0) after 1m0s
[17:58:08.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:14:0)
[17:58:08.142] Timer tick, asking Buildbucket for the build status
[17:58:08.428] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:08.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:15:0) after 1m0s
[17:59:08.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:15:0)
[17:59:08.489] Timer tick, asking Buildbucket for the build status
[17:59:08.595] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:08.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:16:0) after 1m0s
[18:00:08.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:16:0)
[18:00:08.733] Timer tick, asking Buildbucket for the build status
[18:00:08.852] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:08.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:17:0) after 1m0s
[18:01:09.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:17:0)
[18:01:09.023] Timer tick, asking Buildbucket for the build status
[18:01:09.241] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:09.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:18:0) after 1m0s
[18:02:09.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:18:0)
[18:02:09.397] Timer tick, asking Buildbucket for the build status
[18:02:09.542] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:09.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:19:0) after 1m0s
[18:03:09.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:19:0)
[18:03:09.633] Timer tick, asking Buildbucket for the build status
[18:03:09.848] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:09.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:20:0) after 1m0s
[18:04:09.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:20:0)
[18:04:09.900] Timer tick, asking Buildbucket for the build status
[18:04:10.174] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:10.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:21:0) after 1m0s
[18:05:10.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:21:0)
[18:05:10.263] Timer tick, asking Buildbucket for the build status
[18:05:10.524] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:10.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:22:0) after 1m0s
[18:06:10.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:22:0)
[18:06:10.638] Timer tick, asking Buildbucket for the build status
[18:06:11.514] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:11.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:23:0) after 1m0s
[18:07:14.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:23:0)
[18:07:14.119] Timer tick, asking Buildbucket for the build status
[18:07:15.295] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:15.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:24:0) after 1m0s
[18:08:15.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:24:0)
[18:08:15.329] Timer tick, asking Buildbucket for the build status
[18:08:16.132] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:16.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:25:0) after 1m0s
[18:09:16.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:25:0)
[18:09:16.318] Timer tick, asking Buildbucket for the build status
[18:09:16.664] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:16.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:26:0) after 1m0s
[18:10:16.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:26:0)
[18:10:16.784] Timer tick, asking Buildbucket for the build status
[18:10:16.993] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:16.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:27:0) after 1m0s
[18:11:17.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:27:0)
[18:11:17.134] Timer tick, asking Buildbucket for the build status
[18:11:17.583] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:17.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:28:0) after 1m0s
[18:12:17.712] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:28:0)
[18:12:17.712] Timer tick, asking Buildbucket for the build status
[18:12:18.822] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:18.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:29:0) after 1m0s
[18:13:19.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:29:0)
[18:13:19.192] Timer tick, asking Buildbucket for the build status
[18:13:19.556] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:19.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:30:0) after 1m0s
[18:14:19.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:30:0)
[18:14:19.620] Timer tick, asking Buildbucket for the build status
[18:14:19.793] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:19.793] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:31:0) after 1m0s
[18:15:19.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:31:0)
[18:15:19.841] Timer tick, asking Buildbucket for the build status
[18:15:20.394] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:20.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:32:0) after 1m0s
[18:16:20.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:32:0)
[18:16:20.480] Timer tick, asking Buildbucket for the build status
[18:16:21.677] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:21.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:33:0) after 1m0s
[18:17:21.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:33:0)
[18:17:21.934] Timer tick, asking Buildbucket for the build status
[18:17:22.201] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:22.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:34:0) after 1m0s
[18:18:22.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:34:0)
[18:18:22.423] Timer tick, asking Buildbucket for the build status
[18:18:22.624] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:22.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:35:0) after 1m0s
[18:19:22.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:35:0)
[18:19:22.881] Timer tick, asking Buildbucket for the build status
[18:19:23.045] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:23.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:36:0) after 1m0s
[18:20:23.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:36:0)
[18:20:23.127] Timer tick, asking Buildbucket for the build status
[18:20:23.309] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:23.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:37:0) after 1m0s
[18:21:23.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:37:0)
[18:21:23.425] Timer tick, asking Buildbucket for the build status
[18:21:23.715] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:23.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:38:0) after 1m0s
[18:22:23.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:38:0)
[18:22:23.725] Timer tick, asking Buildbucket for the build status
[18:22:23.850] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:23.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:39:0) after 1m0s
[18:23:23.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:39:0)
[18:23:23.838] Timer tick, asking Buildbucket for the build status
[18:23:23.960] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:23.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:40:0) after 1m0s
[18:24:24.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:40:0)
[18:24:24.096] Timer tick, asking Buildbucket for the build status
[18:24:25.152] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:25.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:41:0) after 1m0s
[18:25:25.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:41:0)
[18:25:25.280] Timer tick, asking Buildbucket for the build status
[18:25:25.399] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:25.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:42:0) after 1m0s
[18:26:25.599] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:42:0)
[18:26:25.599] Timer tick, asking Buildbucket for the build status
[18:26:26.089] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:26.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:43:0) after 1m0s
[18:27:26.139] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:43:0)
[18:27:26.139] Timer tick, asking Buildbucket for the build status
[18:27:26.253] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:26.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:44:0) after 1m0s
[18:28:26.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:44:0)
[18:28:26.340] Timer tick, asking Buildbucket for the build status
[18:28:26.458] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:26.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:45:0) after 1m0s
[18:29:26.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:45:0)
[18:29:26.542] Timer tick, asking Buildbucket for the build status
[18:29:26.658] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:26.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:46:0) after 1m0s
[18:30:26.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:46:0)
[18:30:26.827] Timer tick, asking Buildbucket for the build status
[18:30:27.058] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:27.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:47:0) after 1m0s
[18:31:27.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:47:0)
[18:31:27.133] Timer tick, asking Buildbucket for the build status
[18:31:28.354] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:28.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:48:0) after 1m0s
[18:32:28.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:48:0)
[18:32:28.531] Timer tick, asking Buildbucket for the build status
[18:32:28.635] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:28.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:49:0) after 1m0s
[18:33:28.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:49:0)
[18:33:28.781] Timer tick, asking Buildbucket for the build status
[18:33:28.892] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:28.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:50:0) after 1m0s
[18:34:28.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:50:0)
[18:34:28.963] Timer tick, asking Buildbucket for the build status
[18:34:29.171] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:29.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:51:0) after 1m0s
[18:35:29.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:51:0)
[18:35:29.194] Timer tick, asking Buildbucket for the build status
[18:35:29.479] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:29.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:52:0) after 1m0s
[18:36:29.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:52:0)
[18:36:29.568] Timer tick, asking Buildbucket for the build status
[18:36:30.093] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:30.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:53:0) after 1m0s
[18:37:30.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:53:0)
[18:37:30.101] Timer tick, asking Buildbucket for the build status
[18:37:30.315] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:30.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:54:0) after 1m0s
[18:38:30.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:54:0)
[18:38:30.434] Timer tick, asking Buildbucket for the build status
[18:38:30.516] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:30.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:55:0) after 1m0s
[18:39:30.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:55:0)
[18:39:30.609] Timer tick, asking Buildbucket for the build status
[18:39:30.886] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:30.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:56:0) after 1m0s
[18:40:30.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:56:0)
[18:40:30.934] Timer tick, asking Buildbucket for the build status
[18:40:31.059] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:31.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:57:0) after 1m0s
[18:41:31.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:57:0)
[18:41:31.093] Timer tick, asking Buildbucket for the build status
[18:41:32.240] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:32.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:58:0) after 1m0s
[18:42:32.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:58:0)
[18:42:32.293] Timer tick, asking Buildbucket for the build status
[18:42:33.074] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:33.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:59:0) after 1m0s
[18:43:33.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:59:0)
[18:43:33.147] Timer tick, asking Buildbucket for the build status
[18:43:33.642] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:33.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:60:0) after 1m0s
[18:44:33.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:60:0)
[18:44:33.687] Timer tick, asking Buildbucket for the build status
[18:44:33.933] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:33.933] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:61:0) after 1m0s
[18:45:33.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:61:0)
[18:45:33.977] Timer tick, asking Buildbucket for the build status
[18:45:34.155] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:34.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:62:0) after 1m0s
[18:46:34.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:62:0)
[18:46:34.264] Timer tick, asking Buildbucket for the build status
[18:46:34.433] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:34.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:63:0) after 1m0s
[18:47:34.666] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:63:0)
[18:47:34.666] Timer tick, asking Buildbucket for the build status
[18:47:34.805] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:34.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:64:0) after 1m0s
[18:48:34.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:64:0)
[18:48:34.912] Timer tick, asking Buildbucket for the build status
[18:48:35.053] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:35.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:65:0) after 1m0s
[18:49:35.388] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:65:0)
[18:49:35.388] Timer tick, asking Buildbucket for the build status
[18:49:35.514] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:35.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:66:0) after 1m0s
[18:50:35.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:66:0)
[18:50:35.598] Timer tick, asking Buildbucket for the build status
[18:50:36.419] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:36.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:67:0) after 1m0s
[18:51:36.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:67:0)
[18:51:36.527] Timer tick, asking Buildbucket for the build status
[18:51:36.684] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:36.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:68:0) after 1m0s
[18:52:36.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:68:0)
[18:52:36.848] Timer tick, asking Buildbucket for the build status
[18:52:36.926] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:36.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:69:0) after 1m0s
[18:53:37.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:69:0)
[18:53:37.212] Timer tick, asking Buildbucket for the build status
[18:53:37.347] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:37.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:70:0) after 1m0s
[18:54:37.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:70:0)
[18:54:37.456] Timer tick, asking Buildbucket for the build status
[18:54:38.498] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:38.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:71:0) after 1m0s
[18:55:38.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:71:0)
[18:55:38.603] Timer tick, asking Buildbucket for the build status
[18:55:38.873] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:38.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:72:0) after 1m0s
[18:56:39.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:72:0)
[18:56:39.008] Timer tick, asking Buildbucket for the build status
[18:56:39.189] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:39.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:73:0) after 1m0s
[18:57:39.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:73:0)
[18:57:39.311] Timer tick, asking Buildbucket for the build status
[18:57:39.734] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:39.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:74:0) after 1m0s
[18:58:39.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:74:0)
[18:58:39.761] Timer tick, asking Buildbucket for the build status
[18:58:40.041] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:40.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:75:0) after 1m0s
[18:59:40.031] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:75:0)
[18:59:40.031] Timer tick, asking Buildbucket for the build status
[18:59:41.107] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:41.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:76:0) after 1m0s
[19:00:41.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:76:0)
[19:00:41.103] Timer tick, asking Buildbucket for the build status
[19:00:41.288] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:41.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:77:0) after 1m0s
[19:01:41.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:77:0)
[19:01:41.326] Timer tick, asking Buildbucket for the build status
[19:01:41.495] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:41.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:78:0) after 1m0s
[19:02:41.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:78:0)
[19:02:41.509] Timer tick, asking Buildbucket for the build status
[19:02:41.624] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:41.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:79:0) after 1m0s
[19:03:41.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:79:0)
[19:03:41.738] Timer tick, asking Buildbucket for the build status
[19:03:41.883] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:41.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:80:0) after 1m0s
[19:04:42.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:80:0)
[19:04:42.096] Timer tick, asking Buildbucket for the build status
[19:04:42.581] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:42.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:81:0) after 1m0s
[19:05:42.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:81:0)
[19:05:42.633] Timer tick, asking Buildbucket for the build status
[19:05:42.743] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:42.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:82:0) after 1m0s
[19:06:42.982] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:82:0)
[19:06:42.982] Timer tick, asking Buildbucket for the build status
[19:06:43.191] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:43.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:83:0) after 1m0s
[19:07:43.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:83:0)
[19:07:43.261] Timer tick, asking Buildbucket for the build status
[19:07:43.444] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:43.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:84:0) after 1m0s
[19:08:43.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:84:0)
[19:08:43.481] Timer tick, asking Buildbucket for the build status
[19:08:43.907] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:43.907] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:85:0) after 1m0s
[19:09:43.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:85:0)
[19:09:43.926] Timer tick, asking Buildbucket for the build status
[19:09:44.033] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:44.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:86:0) after 1m0s
[19:10:44.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:86:0)
[19:10:44.046] Timer tick, asking Buildbucket for the build status
[19:10:45.023] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:45.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:87:0) after 1m0s
[19:11:45.042] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:87:0)
[19:11:45.042] Timer tick, asking Buildbucket for the build status
[19:11:45.424] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:45.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:88:0) after 1m0s
[19:12:45.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:88:0)
[19:12:45.503] Timer tick, asking Buildbucket for the build status
[19:12:45.727] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:45.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:89:0) after 1m0s
[19:13:45.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:89:0)
[19:13:45.777] Timer tick, asking Buildbucket for the build status
[19:13:46.437] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:46.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:90:0) after 1m0s
[19:14:46.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:90:0)
[19:14:46.504] Timer tick, asking Buildbucket for the build status
[19:14:46.610] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:46.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:91:0) after 1m0s
[19:15:46.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:91:0)
[19:15:46.637] Timer tick, asking Buildbucket for the build status
[19:15:46.894] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:46.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:92:0) after 1m0s
[19:16:46.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:92:0)
[19:16:46.976] Timer tick, asking Buildbucket for the build status
[19:16:47.461] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:47.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:93:0) after 1m0s
[19:17:47.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:93:0)
[19:17:47.457] Timer tick, asking Buildbucket for the build status
[19:17:47.558] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:47.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:94:0) after 1m0s
[19:18:47.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:94:0)
[19:18:47.597] Timer tick, asking Buildbucket for the build status
[19:18:47.742] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:47.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:95:0) after 1m0s
[19:19:47.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:95:0)
[19:19:47.881] Timer tick, asking Buildbucket for the build status
[19:19:48.094] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:48.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:96:0) after 1m0s
[19:20:48.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:96:0)
[19:20:48.202] Timer tick, asking Buildbucket for the build status
[19:20:48.324] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:48.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:97:0) after 1m0s
[19:21:48.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:97:0)
[19:21:48.517] Timer tick, asking Buildbucket for the build status
[19:21:49.476] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:49.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:98:0) after 1m0s
[19:22:49.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:98:0)
[19:22:49.543] Timer tick, asking Buildbucket for the build status
[19:22:49.971] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:49.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:99:0) after 1m0s
[19:23:50.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:99:0)
[19:23:50.016] Timer tick, asking Buildbucket for the build status
[19:23:50.163] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:50.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:100:0) after 1m0s
[19:24:50.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:100:0)
[19:24:50.311] Timer tick, asking Buildbucket for the build status
[19:24:50.425] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:50.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:101:0) after 1m0s
[19:25:50.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:101:0)
[19:25:50.458] Timer tick, asking Buildbucket for the build status
[19:25:50.561] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:50.561] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:102:0) after 1m0s
[19:26:50.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:102:0)
[19:26:50.646] Timer tick, asking Buildbucket for the build status
[19:26:51.583] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:51.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:103:0) after 1m0s
[19:27:51.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:103:0)
[19:27:51.677] Timer tick, asking Buildbucket for the build status
[19:27:51.748] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:51.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:104:0) after 1m0s
[19:28:51.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:104:0)
[19:28:51.802] Timer tick, asking Buildbucket for the build status
[19:28:51.901] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:51.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:105:0) after 1m0s
[19:29:52.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:105:0)
[19:29:52.004] Timer tick, asking Buildbucket for the build status
[19:29:52.113] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:52.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:106:0) after 1m0s
[19:30:52.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:106:0)
[19:30:52.151] Timer tick, asking Buildbucket for the build status
[19:30:52.236] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:52.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:107:0) after 1m0s
[19:31:52.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:107:0)
[19:31:52.286] Timer tick, asking Buildbucket for the build status
[19:31:52.615] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:52.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:108:0) after 1m0s
[19:32:52.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:108:0)
[19:32:52.635] Timer tick, asking Buildbucket for the build status
[19:32:52.783] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:52.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:109:0) after 1m0s
[19:33:52.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:109:0)
[19:33:52.807] Timer tick, asking Buildbucket for the build status
[19:33:52.902] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:52.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:110:0) after 1m0s
[19:34:52.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:110:0)
[19:34:52.948] Timer tick, asking Buildbucket for the build status
[19:34:55.021] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:55.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:111:0) after 1m0s
[19:35:55.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:111:0)
[19:35:55.083] Timer tick, asking Buildbucket for the build status
[19:35:55.177] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:55.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:112:0) after 1m0s
[19:36:55.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:112:0)
[19:36:55.210] Timer tick, asking Buildbucket for the build status
[19:36:55.591] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:55.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:113:0) after 1m0s
[19:37:55.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:113:0)
[19:37:55.618] Timer tick, asking Buildbucket for the build status
[19:37:55.930] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:55.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:114:0) after 1m0s
[19:38:55.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:114:0)
[19:38:55.980] Timer tick, asking Buildbucket for the build status
[19:38:56.102] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:56.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:115:0) after 1m0s
[19:39:56.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:115:0)
[19:39:56.114] Timer tick, asking Buildbucket for the build status
[19:39:57.868] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:57.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:116:0) after 1m0s
[19:40:57.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:116:0)
[19:40:57.891] Timer tick, asking Buildbucket for the build status
[19:40:57.973] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:57.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:117:0) after 1m0s
[19:41:58.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:117:0)
[19:41:58.064] Timer tick, asking Buildbucket for the build status
[19:41:58.633] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:58.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:118:0) after 1m0s
[19:42:58.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:118:0)
[19:42:58.716] Timer tick, asking Buildbucket for the build status
[19:42:58.842] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:58.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:119:0) after 1m0s
[19:43:58.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:119:0)
[19:43:58.952] Timer tick, asking Buildbucket for the build status
[19:44:00.493] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:00.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:120:0) after 1m0s
[19:45:00.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:120:0)
[19:45:00.535] Timer tick, asking Buildbucket for the build status
[19:45:05.734] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:05.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:121:0) after 1m0s
[19:46:05.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:121:0)
[19:46:05.826] Timer tick, asking Buildbucket for the build status
[19:46:06.493] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:06.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:122:0) after 1m0s
[19:47:06.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:122:0)
[19:47:06.562] Timer tick, asking Buildbucket for the build status
[19:47:07.025] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:07.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:123:0) after 1m0s
[19:48:07.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:123:0)
[19:48:07.045] Timer tick, asking Buildbucket for the build status
[19:48:07.158] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:07.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:124:0) after 1m0s
[19:49:07.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:124:0)
[19:49:07.252] Timer tick, asking Buildbucket for the build status
[19:49:07.423] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:07.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:125:0) after 1m0s
[19:50:07.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:125:0)
[19:50:07.458] Timer tick, asking Buildbucket for the build status
[19:50:07.569] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:07.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:126:0) after 1m0s
[19:51:07.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:126:0)
[19:51:07.625] Timer tick, asking Buildbucket for the build status
[19:51:07.910] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:07.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:127:0) after 1m0s
[19:52:07.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:127:0)
[19:52:07.971] Timer tick, asking Buildbucket for the build status
[19:52:08.635] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:08.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:128:0) after 1m0s
[19:53:08.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:128:0)
[19:53:08.703] Timer tick, asking Buildbucket for the build status
[19:53:08.799] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:08.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:129:0) after 1m0s
[19:54:08.885] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:129:0)
[19:54:08.885] Timer tick, asking Buildbucket for the build status
[19:54:09.163] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:09.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:130:0) after 1m0s
[19:55:09.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:130:0)
[19:55:09.232] Timer tick, asking Buildbucket for the build status
[19:55:09.519] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:09.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:131:0) after 1m0s
[19:56:09.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:131:0)
[19:56:09.525] Timer tick, asking Buildbucket for the build status
[19:56:11.010] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:11.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:132:0) after 1m0s
[19:57:11.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:132:0)
[19:57:11.062] Timer tick, asking Buildbucket for the build status
[19:57:11.212] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:11.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:133:0) after 1m0s
[19:58:11.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:133:0)
[19:58:11.261] Timer tick, asking Buildbucket for the build status
[19:58:11.800] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:11.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:134:0) after 1m0s
[19:59:11.854] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:134:0)
[19:59:11.854] Timer tick, asking Buildbucket for the build status
[19:59:11.956] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:11.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:135:0) after 1m0s
[20:00:12.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:135:0)
[20:00:12.064] Timer tick, asking Buildbucket for the build status
[20:00:12.282] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:12.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:136:0) after 1m0s
[20:01:12.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:136:0)
[20:01:12.371] Timer tick, asking Buildbucket for the build status
[20:01:12.677] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:12.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:137:0) after 1m0s
[20:02:12.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:137:0)
[20:02:12.787] Timer tick, asking Buildbucket for the build status
[20:02:12.901] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:12.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:138:0) after 1m0s
[20:03:12.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:138:0)
[20:03:12.906] Timer tick, asking Buildbucket for the build status
[20:03:13.696] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:13.696] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:139:0) after 1m0s
[20:04:13.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:139:0)
[20:04:13.774] Timer tick, asking Buildbucket for the build status
[20:04:13.914] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:13.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:140:0) after 1m0s
[20:05:13.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:140:0)
[20:05:13.940] Timer tick, asking Buildbucket for the build status
[20:05:14.031] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:14.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:141:0) after 1m0s
[20:06:14.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:141:0)
[20:06:14.714] Timer tick, asking Buildbucket for the build status
[20:06:15.323] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:15.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:142:0) after 1m0s
[20:07:15.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:142:0)
[20:07:15.533] Timer tick, asking Buildbucket for the build status
[20:07:15.988] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:15.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:143:0) after 1m0s
[20:08:16.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:143:0)
[20:08:16.204] Timer tick, asking Buildbucket for the build status
[20:08:16.389] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:16.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:144:0) after 1m0s
[20:09:16.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:144:0)
[20:09:16.474] Timer tick, asking Buildbucket for the build status
[20:09:16.645] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:16.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:145:0) after 1m0s
[20:10:16.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:145:0)
[20:10:16.770] Timer tick, asking Buildbucket for the build status
[20:10:17.591] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:17.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:146:0) after 1m0s
[20:11:17.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:146:0)
[20:11:17.766] Timer tick, asking Buildbucket for the build status
[20:11:17.936] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:17.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:147:0) after 1m0s
[20:12:18.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:147:0)
[20:12:18.086] Timer tick, asking Buildbucket for the build status
[20:12:18.210] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:18.210] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:148:0) after 1m0s
[20:13:18.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:148:0)
[20:13:18.969] Timer tick, asking Buildbucket for the build status
[20:13:20.931] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:20.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:149:0) after 1m0s
[20:14:21.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:149:0)
[20:14:21.772] Timer tick, asking Buildbucket for the build status
[20:14:21.914] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:21.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:150:0) after 1m0s
[20:15:21.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:150:0)
[20:15:21.943] Timer tick, asking Buildbucket for the build status
[20:15:22.044] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:22.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:151:0) after 1m0s
[20:16:22.087] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:151:0)
[20:16:22.087] Timer tick, asking Buildbucket for the build status
[20:16:22.377] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:22.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:152:0) after 1m0s
[20:17:22.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:152:0)
[20:17:22.478] Timer tick, asking Buildbucket for the build status
[20:17:22.636] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:22.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:153:0) after 1m0s
[20:18:22.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:153:0)
[20:18:22.720] Timer tick, asking Buildbucket for the build status
[20:18:22.902] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:22.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:154:0) after 1m0s
[20:19:23.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:154:0)
[20:19:23.059] Timer tick, asking Buildbucket for the build status
[20:19:24.233] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:24.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:155:0) after 1m0s
[20:20:24.715] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:155:0)
[20:20:24.715] Timer tick, asking Buildbucket for the build status
[20:20:24.786] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:24.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:156:0) after 1m0s
[20:21:24.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:156:0)
[20:21:24.876] Timer tick, asking Buildbucket for the build status
[20:21:25.103] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:25.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:157:0) after 1m0s
[20:22:25.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:157:0)
[20:22:25.189] Timer tick, asking Buildbucket for the build status
[20:22:25.364] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:25.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:158:0) after 1m0s
[20:23:25.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:158:0)
[20:23:25.473] Timer tick, asking Buildbucket for the build status
[20:23:25.571] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:25.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:159:0) after 1m0s
[20:24:25.632] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:159:0)
[20:24:25.632] Timer tick, asking Buildbucket for the build status
[20:24:25.949] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:25.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:160:0) after 1m0s
[20:25:26.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:160:0)
[20:25:26.014] Timer tick, asking Buildbucket for the build status
[20:25:26.194] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:26.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:161:0) after 1m0s
[20:26:26.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:161:0)
[20:26:26.232] Timer tick, asking Buildbucket for the build status
[20:26:26.595] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:26.595] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:162:0) after 1m0s
[20:27:26.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:162:0)
[20:27:26.625] Timer tick, asking Buildbucket for the build status
[20:27:26.900] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:26.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:163:0) after 1m0s
[20:28:27.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:163:0)
[20:28:27.247] Timer tick, asking Buildbucket for the build status
[20:28:27.739] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:27.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:164:0) after 1m0s
[20:29:27.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:164:0)
[20:29:27.870] Timer tick, asking Buildbucket for the build status
[20:29:28.006] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:28.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:165:0) after 1m0s
[20:30:28.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:165:0)
[20:30:28.125] Timer tick, asking Buildbucket for the build status
[20:30:29.123] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:29.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:166:0) after 1m0s
[20:31:29.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:166:0)
[20:31:29.292] Timer tick, asking Buildbucket for the build status
[20:31:29.481] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:29.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:167:0) after 1m0s
[20:32:29.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:167:0)
[20:32:29.553] Timer tick, asking Buildbucket for the build status
[20:32:29.682] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:29.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:168:0) after 1m0s
[20:33:29.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:168:0)
[20:33:29.825] Timer tick, asking Buildbucket for the build status
[20:33:30.003] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:30.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:169:0) after 1m0s
[20:34:30.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:169:0)
[20:34:30.062] Timer tick, asking Buildbucket for the build status
[20:34:30.505] Build 8873600676025368816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:30.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9039030746276068768:170:0) after 1m0s
[20:34:31.949] Received PubSub notification, asking Buildbucket for the build status
[20:34:32.052] Build 8873600676025368816: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:34:32.052] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1595882071483830",
    "created_by": "project:nacl",
    "created_ts": "1595871963437416",
    "failure_reason": "BUILD_FAILURE",
    "id": "8873600676025368816",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"title\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-ck4d\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-arm\", \"buildnumber\": 4745, \"got_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-ck4d\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5434-62f16a1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1595872018593372",
    "status": "COMPLETED",
    "status_changed_ts": "1595872019219302",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4745",
      "builder:linux-pnacl-x86_64-tests-arm",
      "buildset:commit/git/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/3008f9e6de122325d8f9dbf02f7cdd51fa1ec306",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9039030746276068768",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8873600676025368816/+/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:4daa3334fd04c610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1595882071704057",
    "url": "https://ci.chromium.org/b/8873600676025368816",
    "utcnow_ts": "1595882072043596"
  }
}
[20:34:32.052] Invocation finished in 2h48m37.773946619s with status FAILED