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

• 41820cd74f31806536462a20b286defcbe5b199d (4 years ago)
• c3051401526bb5e18413d6a62fb4ca46633b3b1b (4 years ago)

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

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

[22:05:22.248] New invocation is queued and will start shortly
[22:05:23.386] Starting the invocation (attempt 1)
[22:05:23.433] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:05:23.433] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:05:23.433] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9050067251998546000",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"41820cd74f31806536462a20b286defcbe5b199d\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\",\"title\":\"41820cd74f31806536462a20b286defcbe5b199d\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\",\"title\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9050067251998546000",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
    "gitiles_ref:refs/heads/master"
  ]
}
[22:05:25.238] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585346723543086",
    "id": "8884637189972800880",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585346724853712",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5149",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050067251998546000",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884637189972800880/+/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": "1585346724853997",
    "url": "https://ci.chromium.org/b/8884637189972800880",
    "utcnow_ts": "1585346725211482"
  }
}
[22:05:25.238] Task URL: https://ci.chromium.org/b/8884637189972800880
[22:05:25.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:2:0) after 1m0s
[22:06:25.271] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:2:0)
[22:06:25.271] Timer tick, asking Buildbucket for the build status
[22:06:25.780] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:25.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:3:0) after 1m0s
[22:07:25.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:3:0)
[22:07:25.945] Timer tick, asking Buildbucket for the build status
[22:07:26.156] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:26.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:4:0) after 1m0s
[22:08:26.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:4:0)
[22:08:26.177] Timer tick, asking Buildbucket for the build status
[22:08:26.356] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:26.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:5:0) after 1m0s
[22:09:26.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:5:0)
[22:09:26.590] Timer tick, asking Buildbucket for the build status
[22:09:26.786] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:26.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:6:0) after 1m0s
[22:10:26.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:6:0)
[22:10:26.786] Timer tick, asking Buildbucket for the build status
[22:10:26.898] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:26.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:7:0) after 1m0s
[22:11:27.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:7:0)
[22:11:27.046] Timer tick, asking Buildbucket for the build status
[22:11:27.263] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:27.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:8:0) after 1m0s
[22:12:27.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:8:0)
[22:12:27.284] Timer tick, asking Buildbucket for the build status
[22:12:27.615] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:27.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:9:0) after 1m0s
[22:13:27.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:9:0)
[22:13:27.900] Timer tick, asking Buildbucket for the build status
[22:13:28.563] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:28.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:10:0) after 1m0s
[22:14:28.822] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:10:0)
[22:14:28.822] Timer tick, asking Buildbucket for the build status
[22:14:29.108] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:29.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:11:0) after 1m0s
[22:15:29.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:11:0)
[22:15:29.385] Timer tick, asking Buildbucket for the build status
[22:15:29.596] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:29.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:12:0) after 1m0s
[22:16:29.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:12:0)
[22:16:29.711] Timer tick, asking Buildbucket for the build status
[22:16:30.151] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:30.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:13:0) after 1m0s
[22:17:30.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:13:0)
[22:17:30.219] Timer tick, asking Buildbucket for the build status
[22:17:30.446] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:30.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:14:0) after 1m0s
[22:18:30.537] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:14:0)
[22:18:30.537] Timer tick, asking Buildbucket for the build status
[22:18:33.624] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:33.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:15:0) after 1m0s
[22:19:33.888] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:15:0)
[22:19:33.888] Timer tick, asking Buildbucket for the build status
[22:19:34.317] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:34.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:16:0) after 1m0s
[22:20:34.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:16:0)
[22:20:34.379] Timer tick, asking Buildbucket for the build status
[22:20:34.575] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:34.575] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:17:0) after 1m0s
[22:21:34.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:17:0)
[22:21:34.703] Timer tick, asking Buildbucket for the build status
[22:21:34.834] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:34.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:18:0) after 1m0s
[22:22:34.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:18:0)
[22:22:34.960] Timer tick, asking Buildbucket for the build status
[22:22:35.156] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:35.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:19:0) after 1m0s
[22:23:35.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:19:0)
[22:23:35.195] Timer tick, asking Buildbucket for the build status
[22:23:39.941] Build 8884637189972800880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:39.941] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:20:0) after 1m0s
[22:23:49.528] Received PubSub notification, asking Buildbucket for the build status
[22:23:49.655] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:20:0)
[22:24:40.011] Timer tick, asking Buildbucket for the build status
[22:24:40.151] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:22:0) after 1m0s
[22:25:40.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:22:0)
[22:25:40.296] Timer tick, asking Buildbucket for the build status
[22:25:40.464] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:40.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:23:0) after 1m0s
[22:26:40.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:23:0)
[22:26:40.838] Timer tick, asking Buildbucket for the build status
[22:26:40.979] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:40.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:24:0) after 1m0s
[22:27:41.056] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:24:0)
[22:27:41.056] Timer tick, asking Buildbucket for the build status
[22:27:41.228] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:41.228] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:25:0) after 1m0s
[22:28:41.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:25:0)
[22:28:41.343] Timer tick, asking Buildbucket for the build status
[22:28:41.483] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:26:0) after 1m0s
[22:29:41.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:26:0)
[22:29:41.707] Timer tick, asking Buildbucket for the build status
[22:29:41.822] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:41.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:27:0) after 1m0s
[22:30:41.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:27:0)
[22:30:41.920] Timer tick, asking Buildbucket for the build status
[22:30:43.032] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:43.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:28:0) after 1m0s
[22:31:43.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:28:0)
[22:31:43.192] Timer tick, asking Buildbucket for the build status
[22:31:43.325] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:43.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:29:0) after 1m0s
[22:32:43.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:29:0)
[22:32:43.491] Timer tick, asking Buildbucket for the build status
[22:32:43.624] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:43.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:30:0) after 1m0s
[22:33:43.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:30:0)
[22:33:43.741] Timer tick, asking Buildbucket for the build status
[22:33:43.971] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:43.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:31:0) after 1m0s
[22:34:44.217] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:31:0)
[22:34:44.217] Timer tick, asking Buildbucket for the build status
[22:34:44.538] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:44.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:32:0) after 1m0s
[22:35:44.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:32:0)
[22:35:44.561] Timer tick, asking Buildbucket for the build status
[22:35:45.015] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:45.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:33:0) after 1m0s
[22:36:45.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:33:0)
[22:36:45.102] Timer tick, asking Buildbucket for the build status
[22:36:45.427] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:45.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:34:0) after 1m0s
[22:37:45.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:34:0)
[22:37:45.603] Timer tick, asking Buildbucket for the build status
[22:37:45.797] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:45.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:35:0) after 1m0s
[22:38:45.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:35:0)
[22:38:45.824] Timer tick, asking Buildbucket for the build status
[22:38:46.925] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:46.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:36:0) after 1m0s
[22:39:46.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:36:0)
[22:39:46.932] Timer tick, asking Buildbucket for the build status
[22:39:47.134] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:47.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:37:0) after 1m0s
[22:40:47.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:37:0)
[22:40:47.157] Timer tick, asking Buildbucket for the build status
[22:40:47.324] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:47.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:38:0) after 1m0s
[22:41:47.368] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:38:0)
[22:41:47.368] Timer tick, asking Buildbucket for the build status
[22:41:47.534] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:47.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:39:0) after 1m0s
[22:42:47.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:39:0)
[22:42:47.562] Timer tick, asking Buildbucket for the build status
[22:42:47.674] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:47.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:40:0) after 1m0s
[22:43:47.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:40:0)
[22:43:47.733] Timer tick, asking Buildbucket for the build status
[22:43:47.945] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:47.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:41:0) after 1m0s
[22:44:47.938] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:41:0)
[22:44:47.938] Timer tick, asking Buildbucket for the build status
[22:44:48.541] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:48.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:42:0) after 1m0s
[22:45:48.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:42:0)
[22:45:48.644] Timer tick, asking Buildbucket for the build status
[22:45:48.763] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:48.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:43:0) after 1m0s
[22:46:48.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:43:0)
[22:46:48.750] Timer tick, asking Buildbucket for the build status
[22:46:48.883] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:48.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:44:0) after 1m0s
[22:47:48.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:44:0)
[22:47:48.959] Timer tick, asking Buildbucket for the build status
[22:47:49.615] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:49.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:45:0) after 1m0s
[22:48:50.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:45:0)
[22:48:50.059] Timer tick, asking Buildbucket for the build status
[22:48:50.183] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:50.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:46:0) after 1m0s
[22:49:50.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:46:0)
[22:49:50.344] Timer tick, asking Buildbucket for the build status
[22:49:50.492] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:50.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:47:0) after 1m0s
[22:50:50.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:47:0)
[22:50:50.644] Timer tick, asking Buildbucket for the build status
[22:50:50.881] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:50.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:48:0) after 1m0s
[22:51:50.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:48:0)
[22:51:50.979] Timer tick, asking Buildbucket for the build status
[22:51:51.157] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:51.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:49:0) after 1m0s
[22:52:51.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:49:0)
[22:52:51.230] Timer tick, asking Buildbucket for the build status
[22:52:51.475] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:51.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:50:0) after 1m0s
[22:53:51.559] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:50:0)
[22:53:51.559] Timer tick, asking Buildbucket for the build status
[22:53:51.713] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:51.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:51:0) after 1m0s
[22:54:51.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:51:0)
[22:54:51.772] Timer tick, asking Buildbucket for the build status
[22:54:51.918] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:51.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:52:0) after 1m0s
[22:55:51.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:52:0)
[22:55:51.946] Timer tick, asking Buildbucket for the build status
[22:55:53.365] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:53.365] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:53:0) after 1m0s
[22:56:53.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:53:0)
[22:56:53.441] Timer tick, asking Buildbucket for the build status
[22:56:53.671] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:54:0) after 1m0s
[22:57:53.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:54:0)
[22:57:53.680] Timer tick, asking Buildbucket for the build status
[22:57:54.348] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:55:0) after 1m0s
[22:58:54.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:55:0)
[22:58:54.470] Timer tick, asking Buildbucket for the build status
[22:58:54.867] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:54.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:56:0) after 1m0s
[22:59:54.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:56:0)
[22:59:54.899] Timer tick, asking Buildbucket for the build status
[22:59:55.220] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:57:0) after 1m0s
[23:00:55.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:57:0)
[23:00:55.269] Timer tick, asking Buildbucket for the build status
[23:00:55.735] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:58:0) after 1m0s
[23:01:55.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:58:0)
[23:01:55.758] Timer tick, asking Buildbucket for the build status
[23:01:56.434] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:56.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:59:0) after 1m0s
[23:02:56.523] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:59:0)
[23:02:56.523] Timer tick, asking Buildbucket for the build status
[23:02:56.681] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:56.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:60:0) after 1m0s
[23:03:56.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:60:0)
[23:03:56.744] Timer tick, asking Buildbucket for the build status
[23:03:56.991] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:56.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:61:0) after 1m0s
[23:04:56.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:61:0)
[23:04:56.987] Timer tick, asking Buildbucket for the build status
[23:04:57.359] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:57.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:62:0) after 1m0s
[23:05:57.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:62:0)
[23:05:57.392] Timer tick, asking Buildbucket for the build status
[23:05:58.148] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:58.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:63:0) after 1m0s
[23:06:58.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:63:0)
[23:06:58.168] Timer tick, asking Buildbucket for the build status
[23:06:58.623] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:58.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:64:0) after 1m0s
[23:07:58.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:64:0)
[23:07:58.674] Timer tick, asking Buildbucket for the build status
[23:07:58.890] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:58.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:65:0) after 1m0s
[23:08:58.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:65:0)
[23:08:58.984] Timer tick, asking Buildbucket for the build status
[23:08:59.175] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:59.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:66:0) after 1m0s
[23:09:59.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:66:0)
[23:09:59.196] Timer tick, asking Buildbucket for the build status
[23:09:59.372] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:59.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:67:0) after 1m0s
[23:10:59.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:67:0)
[23:10:59.897] Timer tick, asking Buildbucket for the build status
[23:11:00.076] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:00.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:68:0) after 1m0s
[23:12:00.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:68:0)
[23:12:00.487] Timer tick, asking Buildbucket for the build status
[23:12:00.636] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:00.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:69:0) after 1m0s
[23:13:01.412] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:69:0)
[23:13:01.412] Timer tick, asking Buildbucket for the build status
[23:13:01.578] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:01.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:70:0) after 1m0s
[23:14:01.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:70:0)
[23:14:01.957] Timer tick, asking Buildbucket for the build status
[23:14:02.128] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:02.128] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:71:0) after 1m0s
[23:15:02.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:71:0)
[23:15:02.357] Timer tick, asking Buildbucket for the build status
[23:15:02.993] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:02.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:72:0) after 1m0s
[23:16:03.131] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:72:0)
[23:16:03.131] Timer tick, asking Buildbucket for the build status
[23:16:03.336] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:03.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:73:0) after 1m0s
[23:17:03.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:73:0)
[23:17:03.631] Timer tick, asking Buildbucket for the build status
[23:17:06.931] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:06.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:74:0) after 1m0s
[23:18:06.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:74:0)
[23:18:06.988] Timer tick, asking Buildbucket for the build status
[23:18:07.153] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:07.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:75:0) after 1m0s
[23:19:07.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:75:0)
[23:19:07.218] Timer tick, asking Buildbucket for the build status
[23:19:07.759] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:07.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:76:0) after 1m0s
[23:20:07.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:76:0)
[23:20:07.846] Timer tick, asking Buildbucket for the build status
[23:20:08.327] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:08.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:77:0) after 1m0s
[23:21:08.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:77:0)
[23:21:08.527] Timer tick, asking Buildbucket for the build status
[23:21:08.906] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:08.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:78:0) after 1m0s
[23:22:08.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:78:0)
[23:22:08.948] Timer tick, asking Buildbucket for the build status
[23:22:09.293] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:09.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:79:0) after 1m0s
[23:23:09.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:79:0)
[23:23:09.318] Timer tick, asking Buildbucket for the build status
[23:23:09.577] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:09.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:80:0) after 1m0s
[23:24:09.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:80:0)
[23:24:09.576] Timer tick, asking Buildbucket for the build status
[23:24:09.824] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:09.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:81:0) after 1m0s
[23:25:09.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:81:0)
[23:25:09.890] Timer tick, asking Buildbucket for the build status
[23:25:10.058] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:10.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:82:0) after 1m0s
[23:26:10.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:82:0)
[23:26:10.235] Timer tick, asking Buildbucket for the build status
[23:26:10.392] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:83:0) after 1m0s
[23:27:10.514] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:83:0)
[23:27:10.514] Timer tick, asking Buildbucket for the build status
[23:27:10.769] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:84:0) after 1m0s
[23:28:10.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:84:0)
[23:28:10.846] Timer tick, asking Buildbucket for the build status
[23:28:11.037] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:11.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:85:0) after 1m0s
[23:29:11.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:85:0)
[23:29:11.161] Timer tick, asking Buildbucket for the build status
[23:29:11.605] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:11.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:86:0) after 1m0s
[23:30:11.710] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:86:0)
[23:30:11.710] Timer tick, asking Buildbucket for the build status
[23:30:11.872] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:11.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:87:0) after 1m0s
[23:31:11.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:87:0)
[23:31:11.929] Timer tick, asking Buildbucket for the build status
[23:31:12.183] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:12.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:88:0) after 1m0s
[23:32:12.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:88:0)
[23:32:12.637] Timer tick, asking Buildbucket for the build status
[23:32:13.088] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:13.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:89:0) after 1m0s
[23:33:13.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:89:0)
[23:33:13.291] Timer tick, asking Buildbucket for the build status
[23:33:13.428] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:13.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:90:0) after 1m0s
[23:34:13.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:90:0)
[23:34:13.745] Timer tick, asking Buildbucket for the build status
[23:34:13.881] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:13.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:91:0) after 1m0s
[23:35:14.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:91:0)
[23:35:14.044] Timer tick, asking Buildbucket for the build status
[23:35:14.322] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:14.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:92:0) after 1m0s
[23:36:14.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:92:0)
[23:36:14.402] Timer tick, asking Buildbucket for the build status
[23:36:14.547] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:14.547] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:93:0) after 1m0s
[23:37:14.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:93:0)
[23:37:14.553] Timer tick, asking Buildbucket for the build status
[23:37:14.742] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:14.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:94:0) after 1m0s
[23:38:14.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:94:0)
[23:38:14.842] Timer tick, asking Buildbucket for the build status
[23:38:15.567] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:15.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:95:0) after 1m0s
[23:39:15.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:95:0)
[23:39:15.625] Timer tick, asking Buildbucket for the build status
[23:39:15.848] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:15.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:96:0) after 1m0s
[23:40:15.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:96:0)
[23:40:15.858] Timer tick, asking Buildbucket for the build status
[23:40:16.032] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:16.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:97:0) after 1m0s
[23:41:16.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:97:0)
[23:41:16.032] Timer tick, asking Buildbucket for the build status
[23:41:16.165] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:16.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:98:0) after 1m0s
[23:42:16.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:98:0)
[23:42:16.269] Timer tick, asking Buildbucket for the build status
[23:42:16.598] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:16.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:99:0) after 1m0s
[23:43:16.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:99:0)
[23:43:16.717] Timer tick, asking Buildbucket for the build status
[23:43:16.884] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:16.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:100:0) after 1m0s
[23:44:17.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:100:0)
[23:44:17.009] Timer tick, asking Buildbucket for the build status
[23:44:17.278] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:17.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:101:0) after 1m0s
[23:45:17.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:101:0)
[23:45:17.394] Timer tick, asking Buildbucket for the build status
[23:45:17.540] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:17.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:102:0) after 1m0s
[23:46:17.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:102:0)
[23:46:17.593] Timer tick, asking Buildbucket for the build status
[23:46:17.806] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:17.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:103:0) after 1m0s
[23:47:17.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:103:0)
[23:47:17.935] Timer tick, asking Buildbucket for the build status
[23:47:18.250] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:18.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:104:0) after 1m0s
[23:48:18.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:104:0)
[23:48:18.301] Timer tick, asking Buildbucket for the build status
[23:48:18.946] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:18.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:105:0) after 1m0s
[23:49:19.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:105:0)
[23:49:19.035] Timer tick, asking Buildbucket for the build status
[23:49:19.562] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:19.562] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:106:0) after 1m0s
[23:50:19.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:106:0)
[23:50:19.580] Timer tick, asking Buildbucket for the build status
[23:50:19.805] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:19.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:107:0) after 1m0s
[23:51:19.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:107:0)
[23:51:19.832] Timer tick, asking Buildbucket for the build status
[23:51:19.943] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:19.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:108:0) after 1m0s
[23:52:19.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:108:0)
[23:52:19.999] Timer tick, asking Buildbucket for the build status
[23:52:20.121] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:20.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:109:0) after 1m0s
[23:53:20.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:109:0)
[23:53:20.192] Timer tick, asking Buildbucket for the build status
[23:53:20.362] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:110:0) after 1m0s
[23:54:20.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:110:0)
[23:54:20.437] Timer tick, asking Buildbucket for the build status
[23:54:20.573] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:20.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:111:0) after 1m0s
[23:55:20.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:111:0)
[23:55:20.692] Timer tick, asking Buildbucket for the build status
[23:55:20.911] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:20.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:112:0) after 1m0s
[23:56:21.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:112:0)
[23:56:21.118] Timer tick, asking Buildbucket for the build status
[23:56:21.297] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:21.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:113:0) after 1m0s
[23:57:21.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:113:0)
[23:57:21.405] Timer tick, asking Buildbucket for the build status
[23:57:21.605] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:21.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:114:0) after 1m0s
[23:58:21.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:114:0)
[23:58:21.608] Timer tick, asking Buildbucket for the build status
[23:58:22.612] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:22.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:115:0) after 1m0s
[23:59:22.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:115:0)
[23:59:22.650] Timer tick, asking Buildbucket for the build status
[23:59:22.795] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:22.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:116:0) after 1m0s
[00:00:22.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:116:0)
[00:00:22.967] Timer tick, asking Buildbucket for the build status
[00:00:23.142] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:23.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:117:0) after 1m0s
[00:01:23.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:117:0)
[00:01:23.261] Timer tick, asking Buildbucket for the build status
[00:01:23.614] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:23.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:118:0) after 1m0s
[00:02:23.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:118:0)
[00:02:23.875] Timer tick, asking Buildbucket for the build status
[00:02:24.673] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:24.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:119:0) after 1m0s
[00:03:24.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:119:0)
[00:03:24.768] Timer tick, asking Buildbucket for the build status
[00:03:25.265] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:25.265] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:120:0) after 1m0s
[00:04:25.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:120:0)
[00:04:25.294] Timer tick, asking Buildbucket for the build status
[00:04:25.424] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:25.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:121:0) after 1m0s
[00:05:25.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:121:0)
[00:05:25.498] Timer tick, asking Buildbucket for the build status
[00:05:25.777] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:25.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:122:0) after 1m0s
[00:06:25.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:122:0)
[00:06:25.802] Timer tick, asking Buildbucket for the build status
[00:06:25.885] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:25.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:123:0) after 1m0s
[00:07:26.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:123:0)
[00:07:26.048] Timer tick, asking Buildbucket for the build status
[00:07:26.236] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:26.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:124:0) after 1m0s
[00:08:26.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:124:0)
[00:08:26.288] Timer tick, asking Buildbucket for the build status
[00:08:26.938] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:26.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:125:0) after 1m0s
[00:09:27.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:125:0)
[00:09:27.020] Timer tick, asking Buildbucket for the build status
[00:09:27.284] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:27.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:126:0) after 1m0s
[00:10:27.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:126:0)
[00:10:27.317] Timer tick, asking Buildbucket for the build status
[00:10:27.506] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:27.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:127:0) after 1m0s
[00:11:27.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:127:0)
[00:11:27.565] Timer tick, asking Buildbucket for the build status
[00:11:28.069] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:28.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:128:0) after 1m0s
[00:12:28.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:128:0)
[00:12:28.108] Timer tick, asking Buildbucket for the build status
[00:12:28.226] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:28.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:129:0) after 1m0s
[00:13:28.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:129:0)
[00:13:28.270] Timer tick, asking Buildbucket for the build status
[00:13:32.062] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:32.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:130:0) after 1m0s
[00:14:32.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:130:0)
[00:14:32.054] Timer tick, asking Buildbucket for the build status
[00:14:32.421] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:32.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:131:0) after 1m0s
[00:15:32.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:131:0)
[00:15:32.553] Timer tick, asking Buildbucket for the build status
[00:15:33.108] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:33.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:132:0) after 1m0s
[00:16:33.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:132:0)
[00:16:33.149] Timer tick, asking Buildbucket for the build status
[00:16:33.516] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:33.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:133:0) after 1m0s
[00:17:33.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:133:0)
[00:17:33.578] Timer tick, asking Buildbucket for the build status
[00:17:33.864] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:33.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:134:0) after 1m0s
[00:18:33.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:134:0)
[00:18:33.912] Timer tick, asking Buildbucket for the build status
[00:18:34.201] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:34.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:135:0) after 1m0s
[00:19:34.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:135:0)
[00:19:34.228] Timer tick, asking Buildbucket for the build status
[00:19:34.708] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:34.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:136:0) after 1m0s
[00:20:34.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:136:0)
[00:20:34.774] Timer tick, asking Buildbucket for the build status
[00:20:34.962] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:34.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:137:0) after 1m0s
[00:21:35.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:137:0)
[00:21:35.015] Timer tick, asking Buildbucket for the build status
[00:21:35.454] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:35.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:138:0) after 1m0s
[00:22:35.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:138:0)
[00:22:35.582] Timer tick, asking Buildbucket for the build status
[00:22:35.722] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:35.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:139:0) after 1m0s
[00:23:35.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:139:0)
[00:23:35.768] Timer tick, asking Buildbucket for the build status
[00:23:36.951] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:36.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:140:0) after 1m0s
[00:24:37.050] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:140:0)
[00:24:37.050] Timer tick, asking Buildbucket for the build status
[00:24:37.304] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:37.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:141:0) after 1m0s
[00:25:37.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:141:0)
[00:25:37.381] Timer tick, asking Buildbucket for the build status
[00:25:37.552] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:37.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:142:0) after 1m0s
[00:26:37.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:142:0)
[00:26:37.603] Timer tick, asking Buildbucket for the build status
[00:26:37.712] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:37.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:143:0) after 1m0s
[00:27:37.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:143:0)
[00:27:37.861] Timer tick, asking Buildbucket for the build status
[00:27:38.118] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:38.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:144:0) after 1m0s
[00:28:38.227] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:144:0)
[00:28:38.227] Timer tick, asking Buildbucket for the build status
[00:28:40.827] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:40.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:145:0) after 1m0s
[00:29:40.848] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:145:0)
[00:29:40.848] Timer tick, asking Buildbucket for the build status
[00:29:41.043] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:41.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:146:0) after 1m0s
[00:30:41.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:146:0)
[00:30:41.164] Timer tick, asking Buildbucket for the build status
[00:30:41.316] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:41.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:147:0) after 1m0s
[00:31:41.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:147:0)
[00:31:41.394] Timer tick, asking Buildbucket for the build status
[00:31:41.610] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:41.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:148:0) after 1m0s
[00:32:41.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:148:0)
[00:32:41.792] Timer tick, asking Buildbucket for the build status
[00:32:42.034] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:42.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:149:0) after 1m0s
[00:33:42.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:149:0)
[00:33:42.261] Timer tick, asking Buildbucket for the build status
[00:33:42.756] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:42.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:150:0) after 1m0s
[00:34:42.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:150:0)
[00:34:42.817] Timer tick, asking Buildbucket for the build status
[00:34:43.019] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:43.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:151:0) after 1m0s
[00:35:43.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:151:0)
[00:35:43.099] Timer tick, asking Buildbucket for the build status
[00:35:43.234] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:43.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:152:0) after 1m0s
[00:36:43.372] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:152:0)
[00:36:43.372] Timer tick, asking Buildbucket for the build status
[00:36:43.971] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:43.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:153:0) after 1m0s
[00:37:44.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:153:0)
[00:37:44.071] Timer tick, asking Buildbucket for the build status
[00:37:45.947] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:45.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:154:0) after 1m0s
[00:38:45.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:154:0)
[00:38:45.985] Timer tick, asking Buildbucket for the build status
[00:38:46.209] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:46.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:155:0) after 1m0s
[00:39:46.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:155:0)
[00:39:46.199] Timer tick, asking Buildbucket for the build status
[00:39:46.807] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:46.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:156:0) after 1m0s
[00:40:46.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:156:0)
[00:40:46.838] Timer tick, asking Buildbucket for the build status
[00:40:47.009] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:47.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:157:0) after 1m0s
[00:41:47.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:157:0)
[00:41:47.037] Timer tick, asking Buildbucket for the build status
[00:41:47.211] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:47.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:158:0) after 1m0s
[00:42:47.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:158:0)
[00:42:47.246] Timer tick, asking Buildbucket for the build status
[00:42:47.444] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:47.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:159:0) after 1m0s
[00:43:47.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:159:0)
[00:43:47.592] Timer tick, asking Buildbucket for the build status
[00:43:47.905] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:47.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:160:0) after 1m0s
[00:44:48.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:160:0)
[00:44:48.008] Timer tick, asking Buildbucket for the build status
[00:44:48.370] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:48.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:161:0) after 1m0s
[00:45:48.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:161:0)
[00:45:48.396] Timer tick, asking Buildbucket for the build status
[00:45:48.552] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:48.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:162:0) after 1m0s
[00:46:48.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:162:0)
[00:46:48.647] Timer tick, asking Buildbucket for the build status
[00:46:48.805] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:48.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:163:0) after 1m0s
[00:47:48.850] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:163:0)
[00:47:48.850] Timer tick, asking Buildbucket for the build status
[00:47:49.075] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:49.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:164:0) after 1m0s
[00:48:49.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:164:0)
[00:48:49.137] Timer tick, asking Buildbucket for the build status
[00:48:49.472] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:49.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:165:0) after 1m0s
[00:49:49.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:165:0)
[00:49:49.648] Timer tick, asking Buildbucket for the build status
[00:49:49.979] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:49.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:166:0) after 1m0s
[00:50:50.080] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:166:0)
[00:50:50.080] Timer tick, asking Buildbucket for the build status
[00:50:50.272] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:167:0) after 1m0s
[00:51:50.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:167:0)
[00:51:50.491] Timer tick, asking Buildbucket for the build status
[00:51:50.665] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:50.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:168:0) after 1m0s
[00:52:50.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:168:0)
[00:52:50.777] Timer tick, asking Buildbucket for the build status
[00:52:51.080] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:51.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:169:0) after 1m0s
[00:53:51.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:169:0)
[00:53:51.307] Timer tick, asking Buildbucket for the build status
[00:53:53.138] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:53.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:170:0) after 1m0s
[00:54:53.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:170:0)
[00:54:53.216] Timer tick, asking Buildbucket for the build status
[00:54:53.454] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:53.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:171:0) after 1m0s
[00:55:53.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:171:0)
[00:55:53.504] Timer tick, asking Buildbucket for the build status
[00:55:53.958] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:53.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:172:0) after 1m0s
[00:56:54.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:172:0)
[00:56:54.055] Timer tick, asking Buildbucket for the build status
[00:56:54.280] Build 8884637189972800880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:54.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050067251998546000:173:0) after 1m0s
[00:57:42.225] Received PubSub notification, asking Buildbucket for the build status
[00:57:42.427] Build 8884637189972800880: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:57:42.427] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1585357061723066",
    "created_by": "project:nacl",
    "created_ts": "1585346723543086",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884637189972800880",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"41820cd74f31806536462a20b286defcbe5b199d\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@41820cd74f31806536462a20b286defcbe5b199d\", \"title\": \"41820cd74f31806536462a20b286defcbe5b199d\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/41820cd74f31806536462a20b286defcbe5b199d\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"title\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-1-flwl\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5149, \"got_revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"c3051401526bb5e18413d6a62fb4ca46633b3b1b\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-1-flwl\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4901-91f024a\"], \"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": "1585347828686298",
    "status": "COMPLETED",
    "status_changed_ts": "1585347829170815",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5149",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/c3051401526bb5e18413d6a62fb4ca46633b3b1b",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050067251998546000",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884637189972800880/+/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:4b36d8cfea7e7610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585357061820119",
    "url": "https://ci.chromium.org/b/8884637189972800880",
    "utcnow_ts": "1585357062378598"
  }
}
[00:57:42.427] Invocation finished in 2h52m20.200797716s with status FAILED