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

• ffed96d1528df9f114a30b14d4eb06120c8fb022 (4 years ago)

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

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

[06:10:18.173] New invocation is queued and will start shortly
[06:10:19.268] Starting the invocation (attempt 1)
[06:10:19.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:10:19.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:10:19.300] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9050399130576859168",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"title\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_64",
    "scheduler_invocation_id:9050399130576859168",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "gitiles_ref:refs/heads/master"
  ]
}
[06:10:20.131] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585030219388538",
    "id": "8884969068633362080",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585030219968285",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5093",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050399130576859168",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884969068633362080/+/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": "1585030219968450",
    "url": "https://ci.chromium.org/b/8884969068633362080",
    "utcnow_ts": "1585030220124757"
  }
}
[06:10:20.131] Task URL: https://ci.chromium.org/b/8884969068633362080
[06:10:20.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:2:0) after 1m0s
[06:11:20.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:2:0)
[06:11:20.184] Timer tick, asking Buildbucket for the build status
[06:11:20.375] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:11:20.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:3:0) after 1m0s
[06:12:20.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:3:0)
[06:12:20.391] Timer tick, asking Buildbucket for the build status
[06:12:21.160] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:12:21.160] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:4:0) after 1m0s
[06:13:21.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:4:0)
[06:13:21.186] Timer tick, asking Buildbucket for the build status
[06:13:21.485] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:13:21.485] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:5:0) after 1m0s
[06:14:21.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:5:0)
[06:14:21.546] Timer tick, asking Buildbucket for the build status
[06:14:21.672] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:14:21.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:6:0) after 1m0s
[06:15:21.654] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:6:0)
[06:15:21.654] Timer tick, asking Buildbucket for the build status
[06:15:21.755] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:15:21.755] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:7:0) after 1m0s
[06:16:21.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:7:0)
[06:16:21.819] Timer tick, asking Buildbucket for the build status
[06:16:21.990] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:21.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:8:0) after 1m0s
[06:17:22.011] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:8:0)
[06:17:22.012] Timer tick, asking Buildbucket for the build status
[06:17:22.409] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:22.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:9:0) after 1m0s
[06:18:22.430] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:9:0)
[06:18:22.430] Timer tick, asking Buildbucket for the build status
[06:18:22.702] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:22.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:10:0) after 1m0s
[06:19:22.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:10:0)
[06:19:22.722] Timer tick, asking Buildbucket for the build status
[06:19:22.981] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:19:22.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:11:0) after 1m0s
[06:20:23.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:11:0)
[06:20:23.004] Timer tick, asking Buildbucket for the build status
[06:20:23.116] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:20:23.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:12:0) after 1m0s
[06:21:23.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:12:0)
[06:21:23.213] Timer tick, asking Buildbucket for the build status
[06:21:23.367] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:21:23.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:13:0) after 1m0s
[06:22:23.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:13:0)
[06:22:23.394] Timer tick, asking Buildbucket for the build status
[06:22:24.829] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:22:24.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:14:0) after 1m0s
[06:23:24.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:14:0)
[06:23:24.852] Timer tick, asking Buildbucket for the build status
[06:23:28.436] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:23:28.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:15:0) after 1m0s
[06:24:28.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:15:0)
[06:24:28.456] Timer tick, asking Buildbucket for the build status
[06:24:28.792] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:24:28.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:16:0) after 1m0s
[06:25:28.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:16:0)
[06:25:28.798] Timer tick, asking Buildbucket for the build status
[06:25:28.898] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:25:28.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:17:0) after 1m0s
[06:26:28.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:17:0)
[06:26:28.962] Timer tick, asking Buildbucket for the build status
[06:26:29.214] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:26:29.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:18:0) after 1m0s
[06:27:29.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:18:0)
[06:27:29.278] Timer tick, asking Buildbucket for the build status
[06:27:29.541] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:27:29.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:19:0) after 1m0s
[06:28:29.563] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:19:0)
[06:28:29.563] Timer tick, asking Buildbucket for the build status
[06:28:31.044] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:28:31.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:20:0) after 1m0s
[06:29:31.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:20:0)
[06:29:31.132] Timer tick, asking Buildbucket for the build status
[06:29:31.340] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:29:31.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:21:0) after 1m0s
[06:30:31.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:21:0)
[06:30:31.403] Timer tick, asking Buildbucket for the build status
[06:30:31.500] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:30:31.500] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:22:0) after 1m0s
[06:31:31.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:22:0)
[06:31:31.552] Timer tick, asking Buildbucket for the build status
[06:31:31.671] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:31:31.671] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:23:0) after 1m0s
[06:32:31.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:23:0)
[06:32:31.691] Timer tick, asking Buildbucket for the build status
[06:32:32.712] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:32:32.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:24:0) after 1m0s
[06:33:32.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:24:0)
[06:33:32.770] Timer tick, asking Buildbucket for the build status
[06:33:32.882] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:33:32.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:25:0) after 1m0s
[06:34:32.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:25:0)
[06:34:32.940] Timer tick, asking Buildbucket for the build status
[06:34:33.064] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:34:33.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:26:0) after 1m0s
[06:35:33.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:26:0)
[06:35:33.116] Timer tick, asking Buildbucket for the build status
[06:35:33.313] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:35:33.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:27:0) after 1m0s
[06:36:33.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:27:0)
[06:36:33.321] Timer tick, asking Buildbucket for the build status
[06:36:33.413] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:36:33.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:28:0) after 1m0s
[06:37:33.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:28:0)
[06:37:33.470] Timer tick, asking Buildbucket for the build status
[06:37:33.635] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:33.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:29:0) after 1m0s
[06:38:33.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:29:0)
[06:38:33.694] Timer tick, asking Buildbucket for the build status
[06:38:33.816] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:33.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:30:0) after 1m0s
[06:39:33.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:30:0)
[06:39:33.911] Timer tick, asking Buildbucket for the build status
[06:39:34.178] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:34.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:31:0) after 1m0s
[06:40:34.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:31:0)
[06:40:34.189] Timer tick, asking Buildbucket for the build status
[06:40:34.317] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:34.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:32:0) after 1m0s
[06:41:34.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:32:0)
[06:41:34.403] Timer tick, asking Buildbucket for the build status
[06:41:35.493] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:35.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:33:0) after 1m0s
[06:42:35.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:33:0)
[06:42:35.545] Timer tick, asking Buildbucket for the build status
[06:42:37.655] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:37.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:34:0) after 1m0s
[06:43:37.676] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:34:0)
[06:43:37.677] Timer tick, asking Buildbucket for the build status
[06:43:37.783] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:37.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:35:0) after 1m0s
[06:44:37.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:35:0)
[06:44:37.807] Timer tick, asking Buildbucket for the build status
[06:44:37.916] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:37.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:36:0) after 1m0s
[06:45:38.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:36:0)
[06:45:38.091] Timer tick, asking Buildbucket for the build status
[06:45:38.159] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:38.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:37:0) after 1m0s
[06:46:38.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:37:0)
[06:46:38.201] Timer tick, asking Buildbucket for the build status
[06:46:39.381] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:39.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:38:0) after 1m0s
[06:47:39.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:38:0)
[06:47:39.398] Timer tick, asking Buildbucket for the build status
[06:47:39.579] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:39.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:39:0) after 1m0s
[06:48:39.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:39:0)
[06:48:39.594] Timer tick, asking Buildbucket for the build status
[06:48:39.779] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:39.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:40:0) after 1m0s
[06:49:39.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:40:0)
[06:49:39.808] Timer tick, asking Buildbucket for the build status
[06:49:40.279] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:40.279] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:41:0) after 1m0s
[06:50:40.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:41:0)
[06:50:40.308] Timer tick, asking Buildbucket for the build status
[06:50:40.536] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:40.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:42:0) after 1m0s
[06:51:40.545] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:42:0)
[06:51:40.545] Timer tick, asking Buildbucket for the build status
[06:51:40.651] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:40.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:43:0) after 1m0s
[06:52:40.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:43:0)
[06:52:40.775] Timer tick, asking Buildbucket for the build status
[06:52:40.879] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:40.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:44:0) after 1m0s
[06:53:40.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:44:0)
[06:53:40.892] Timer tick, asking Buildbucket for the build status
[06:53:40.971] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:40.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:45:0) after 1m0s
[06:54:41.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:45:0)
[06:54:41.095] Timer tick, asking Buildbucket for the build status
[06:54:41.243] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:41.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:46:0) after 1m0s
[06:55:41.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:46:0)
[06:55:41.423] Timer tick, asking Buildbucket for the build status
[06:55:41.535] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:41.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:47:0) after 1m0s
[06:56:41.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:47:0)
[06:56:41.669] Timer tick, asking Buildbucket for the build status
[06:56:43.272] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:43.272] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:48:0) after 1m0s
[06:57:43.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:48:0)
[06:57:43.333] Timer tick, asking Buildbucket for the build status
[06:57:43.522] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:43.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:49:0) after 1m0s
[06:58:43.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:49:0)
[06:58:43.533] Timer tick, asking Buildbucket for the build status
[06:58:45.354] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:45.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:50:0) after 1m0s
[06:59:45.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:50:0)
[06:59:45.427] Timer tick, asking Buildbucket for the build status
[06:59:45.541] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:45.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:51:0) after 1m0s
[07:00:45.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:51:0)
[07:00:45.620] Timer tick, asking Buildbucket for the build status
[07:00:45.792] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:45.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:52:0) after 1m0s
[07:01:45.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:52:0)
[07:01:45.891] Timer tick, asking Buildbucket for the build status
[07:01:46.339] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:46.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:53:0) after 1m0s
[07:02:46.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:53:0)
[07:02:46.425] Timer tick, asking Buildbucket for the build status
[07:02:46.742] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:46.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:54:0) after 1m0s
[07:03:46.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:54:0)
[07:03:46.922] Timer tick, asking Buildbucket for the build status
[07:03:47.073] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:47.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:55:0) after 1m0s
[07:04:47.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:55:0)
[07:04:47.086] Timer tick, asking Buildbucket for the build status
[07:04:47.807] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:47.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:56:0) after 1m0s
[07:05:47.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:56:0)
[07:05:47.947] Timer tick, asking Buildbucket for the build status
[07:05:48.104] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:48.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:57:0) after 1m0s
[07:06:48.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:57:0)
[07:06:48.124] Timer tick, asking Buildbucket for the build status
[07:06:48.253] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:48.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:58:0) after 1m0s
[07:07:48.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:58:0)
[07:07:48.273] Timer tick, asking Buildbucket for the build status
[07:07:48.405] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:48.405] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:59:0) after 1m0s
[07:08:48.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:59:0)
[07:08:48.424] Timer tick, asking Buildbucket for the build status
[07:08:48.587] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:48.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:60:0) after 1m0s
[07:09:48.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:60:0)
[07:09:48.657] Timer tick, asking Buildbucket for the build status
[07:09:48.871] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:48.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:61:0) after 1m0s
[07:10:48.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:61:0)
[07:10:48.908] Timer tick, asking Buildbucket for the build status
[07:10:49.009] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:49.009] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:62:0) after 1m0s
[07:11:49.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:62:0)
[07:11:49.141] Timer tick, asking Buildbucket for the build status
[07:11:49.411] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:49.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:63:0) after 1m0s
[07:12:49.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:63:0)
[07:12:49.442] Timer tick, asking Buildbucket for the build status
[07:12:49.608] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:49.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:64:0) after 1m0s
[07:13:49.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:64:0)
[07:13:49.647] Timer tick, asking Buildbucket for the build status
[07:13:49.880] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:49.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:65:0) after 1m0s
[07:14:49.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:65:0)
[07:14:49.909] Timer tick, asking Buildbucket for the build status
[07:14:50.191] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:50.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:66:0) after 1m0s
[07:15:50.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:66:0)
[07:15:50.219] Timer tick, asking Buildbucket for the build status
[07:15:50.309] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:50.309] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:67:0) after 1m0s
[07:16:50.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:67:0)
[07:16:50.327] Timer tick, asking Buildbucket for the build status
[07:16:50.477] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:50.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:68:0) after 1m0s
[07:17:50.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:68:0)
[07:17:50.494] Timer tick, asking Buildbucket for the build status
[07:17:50.612] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:50.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:69:0) after 1m0s
[07:18:50.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:69:0)
[07:18:50.631] Timer tick, asking Buildbucket for the build status
[07:18:50.749] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:50.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:70:0) after 1m0s
[07:19:50.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:70:0)
[07:19:50.839] Timer tick, asking Buildbucket for the build status
[07:19:51.767] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:51.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:71:0) after 1m0s
[07:20:51.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:71:0)
[07:20:51.784] Timer tick, asking Buildbucket for the build status
[07:20:51.905] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:51.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:72:0) after 1m0s
[07:21:51.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:72:0)
[07:21:51.874] Timer tick, asking Buildbucket for the build status
[07:21:51.970] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:51.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:73:0) after 1m0s
[07:22:51.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:73:0)
[07:22:51.988] Timer tick, asking Buildbucket for the build status
[07:22:52.109] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:52.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:74:0) after 1m0s
[07:23:52.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:74:0)
[07:23:52.133] Timer tick, asking Buildbucket for the build status
[07:23:52.259] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:52.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:75:0) after 1m0s
[07:24:52.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:75:0)
[07:24:52.282] Timer tick, asking Buildbucket for the build status
[07:24:52.567] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:52.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:76:0) after 1m0s
[07:25:52.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:76:0)
[07:25:52.605] Timer tick, asking Buildbucket for the build status
[07:25:52.799] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:52.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:77:0) after 1m0s
[07:26:52.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:77:0)
[07:26:52.823] Timer tick, asking Buildbucket for the build status
[07:26:52.969] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:52.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:78:0) after 1m0s
[07:27:52.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:78:0)
[07:27:52.987] Timer tick, asking Buildbucket for the build status
[07:27:53.392] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:53.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:79:0) after 1m0s
[07:28:53.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:79:0)
[07:28:53.417] Timer tick, asking Buildbucket for the build status
[07:28:53.517] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:53.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:80:0) after 1m0s
[07:29:53.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:80:0)
[07:29:53.527] Timer tick, asking Buildbucket for the build status
[07:29:53.660] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:53.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:81:0) after 1m0s
[07:30:53.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:81:0)
[07:30:53.750] Timer tick, asking Buildbucket for the build status
[07:30:53.913] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:53.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:82:0) after 1m0s
[07:31:53.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:82:0)
[07:31:53.974] Timer tick, asking Buildbucket for the build status
[07:31:54.372] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:54.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:83:0) after 1m0s
[07:32:54.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:83:0)
[07:32:54.415] Timer tick, asking Buildbucket for the build status
[07:32:55.869] Build 8884969068633362080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:55.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:84:0) after 1m0s
[07:33:11.312] Received PubSub notification, asking Buildbucket for the build status
[07:33:11.596] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:55.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:84:0)
[07:33:55.878] Timer tick, asking Buildbucket for the build status
[07:33:56.082] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:56.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:86:0) after 1m0s
[07:34:56.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:86:0)
[07:34:56.196] Timer tick, asking Buildbucket for the build status
[07:34:56.434] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:56.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:87:0) after 1m0s
[07:35:56.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:87:0)
[07:35:56.481] Timer tick, asking Buildbucket for the build status
[07:35:56.767] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:56.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:88:0) after 1m0s
[07:36:56.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:88:0)
[07:36:56.836] Timer tick, asking Buildbucket for the build status
[07:36:57.099] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:57.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:89:0) after 1m0s
[07:37:57.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:89:0)
[07:37:57.163] Timer tick, asking Buildbucket for the build status
[07:37:57.299] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:57.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:90:0) after 1m0s
[07:38:57.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:90:0)
[07:38:57.453] Timer tick, asking Buildbucket for the build status
[07:38:57.614] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:57.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:91:0) after 1m0s
[07:39:57.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:91:0)
[07:39:57.828] Timer tick, asking Buildbucket for the build status
[07:39:57.998] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:57.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:92:0) after 1m0s
[07:40:58.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:92:0)
[07:40:58.349] Timer tick, asking Buildbucket for the build status
[07:40:58.490] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:58.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:93:0) after 1m0s
[07:41:58.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:93:0)
[07:41:58.598] Timer tick, asking Buildbucket for the build status
[07:41:58.763] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:58.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:94:0) after 1m0s
[07:42:58.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:94:0)
[07:42:58.797] Timer tick, asking Buildbucket for the build status
[07:42:58.940] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:58.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:95:0) after 1m0s
[07:43:59.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:95:0)
[07:43:59.046] Timer tick, asking Buildbucket for the build status
[07:43:59.275] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:59.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:96:0) after 1m0s
[07:44:59.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:96:0)
[07:44:59.349] Timer tick, asking Buildbucket for the build status
[07:44:59.459] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:59.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:97:0) after 1m0s
[07:45:59.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:97:0)
[07:45:59.516] Timer tick, asking Buildbucket for the build status
[07:45:59.611] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:59.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:98:0) after 1m0s
[07:46:59.635] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:98:0)
[07:46:59.635] Timer tick, asking Buildbucket for the build status
[07:46:59.750] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:59.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:99:0) after 1m0s
[07:47:59.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:99:0)
[07:47:59.881] Timer tick, asking Buildbucket for the build status
[07:48:00.148] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:00.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:100:0) after 1m0s
[07:49:00.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:100:0)
[07:49:00.174] Timer tick, asking Buildbucket for the build status
[07:49:00.312] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:00.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:101:0) after 1m0s
[07:50:00.346] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:101:0)
[07:50:00.346] Timer tick, asking Buildbucket for the build status
[07:50:00.506] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:00.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:102:0) after 1m0s
[07:51:00.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:102:0)
[07:51:00.573] Timer tick, asking Buildbucket for the build status
[07:51:00.735] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:00.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:103:0) after 1m0s
[07:52:00.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:103:0)
[07:52:00.806] Timer tick, asking Buildbucket for the build status
[07:52:01.253] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:01.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:104:0) after 1m0s
[07:53:01.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:104:0)
[07:53:01.275] Timer tick, asking Buildbucket for the build status
[07:53:01.442] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:01.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:105:0) after 1m0s
[07:54:01.460] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:105:0)
[07:54:01.460] Timer tick, asking Buildbucket for the build status
[07:54:01.578] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:01.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:106:0) after 1m0s
[07:55:01.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:106:0)
[07:55:01.601] Timer tick, asking Buildbucket for the build status
[07:55:01.974] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:01.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:107:0) after 1m0s
[07:56:02.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:107:0)
[07:56:02.067] Timer tick, asking Buildbucket for the build status
[07:56:02.469] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:02.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:108:0) after 1m0s
[07:57:02.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:108:0)
[07:57:02.546] Timer tick, asking Buildbucket for the build status
[07:57:02.947] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:02.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:109:0) after 1m0s
[07:58:02.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:109:0)
[07:58:02.997] Timer tick, asking Buildbucket for the build status
[07:58:03.149] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:03.149] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:110:0) after 1m0s
[07:59:03.213] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:110:0)
[07:59:03.213] Timer tick, asking Buildbucket for the build status
[07:59:03.434] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:03.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:111:0) after 1m0s
[08:00:03.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:111:0)
[08:00:03.436] Timer tick, asking Buildbucket for the build status
[08:00:03.543] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:03.543] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:112:0) after 1m0s
[08:01:03.641] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:112:0)
[08:01:03.641] Timer tick, asking Buildbucket for the build status
[08:01:03.925] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:03.925] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:113:0) after 1m0s
[08:02:03.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:113:0)
[08:02:03.943] Timer tick, asking Buildbucket for the build status
[08:02:04.064] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:04.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:114:0) after 1m0s
[08:03:04.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:114:0)
[08:03:04.091] Timer tick, asking Buildbucket for the build status
[08:03:04.548] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:04.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:115:0) after 1m0s
[08:04:04.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:115:0)
[08:04:04.579] Timer tick, asking Buildbucket for the build status
[08:04:04.774] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:04.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:116:0) after 1m0s
[08:05:04.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:116:0)
[08:05:04.808] Timer tick, asking Buildbucket for the build status
[08:05:04.969] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:04.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:117:0) after 1m0s
[08:06:05.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:117:0)
[08:06:05.003] Timer tick, asking Buildbucket for the build status
[08:06:05.084] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:05.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:118:0) after 1m0s
[08:07:05.225] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:118:0)
[08:07:05.225] Timer tick, asking Buildbucket for the build status
[08:07:05.383] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:05.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:119:0) after 1m0s
[08:08:05.620] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:119:0)
[08:08:05.620] Timer tick, asking Buildbucket for the build status
[08:08:05.777] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:05.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:120:0) after 1m0s
[08:09:05.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:120:0)
[08:09:05.817] Timer tick, asking Buildbucket for the build status
[08:09:05.942] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:05.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:121:0) after 1m0s
[08:10:06.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:121:0)
[08:10:06.003] Timer tick, asking Buildbucket for the build status
[08:10:06.156] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:06.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:122:0) after 1m0s
[08:11:06.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:122:0)
[08:11:06.167] Timer tick, asking Buildbucket for the build status
[08:11:06.254] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:06.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:123:0) after 1m0s
[08:12:06.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:123:0)
[08:12:06.298] Timer tick, asking Buildbucket for the build status
[08:12:06.532] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:06.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:124:0) after 1m0s
[08:13:06.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:124:0)
[08:13:06.601] Timer tick, asking Buildbucket for the build status
[08:13:06.760] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:06.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:125:0) after 1m0s
[08:14:06.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:125:0)
[08:14:06.834] Timer tick, asking Buildbucket for the build status
[08:14:08.042] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:08.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:126:0) after 1m0s
[08:15:08.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:126:0)
[08:15:08.111] Timer tick, asking Buildbucket for the build status
[08:15:08.177] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:08.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:127:0) after 1m0s
[08:16:08.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:127:0)
[08:16:08.265] Timer tick, asking Buildbucket for the build status
[08:16:08.524] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:08.524] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:128:0) after 1m0s
[08:17:08.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:128:0)
[08:17:08.566] Timer tick, asking Buildbucket for the build status
[08:17:09.891] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:09.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:129:0) after 1m0s
[08:18:09.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:129:0)
[08:18:09.917] Timer tick, asking Buildbucket for the build status
[08:18:10.092] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:10.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:130:0) after 1m0s
[08:19:10.140] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:130:0)
[08:19:10.140] Timer tick, asking Buildbucket for the build status
[08:19:10.470] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:10.470] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:131:0) after 1m0s
[08:20:10.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:131:0)
[08:20:10.551] Timer tick, asking Buildbucket for the build status
[08:20:10.664] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:10.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:132:0) after 1m0s
[08:21:11.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:132:0)
[08:21:11.425] Timer tick, asking Buildbucket for the build status
[08:21:11.627] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:11.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:133:0) after 1m0s
[08:22:12.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:133:0)
[08:22:12.053] Timer tick, asking Buildbucket for the build status
[08:22:12.380] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:12.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:134:0) after 1m0s
[08:23:13.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:134:0)
[08:23:13.453] Timer tick, asking Buildbucket for the build status
[08:23:13.587] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:13.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:135:0) after 1m0s
[08:24:13.728] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:135:0)
[08:24:13.728] Timer tick, asking Buildbucket for the build status
[08:24:13.935] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:13.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:136:0) after 1m0s
[08:25:14.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:136:0)
[08:25:14.865] Timer tick, asking Buildbucket for the build status
[08:25:15.166] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:15.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:137:0) after 1m0s
[08:26:15.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:137:0)
[08:26:15.165] Timer tick, asking Buildbucket for the build status
[08:26:15.252] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:15.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:138:0) after 1m0s
[08:27:15.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:138:0)
[08:27:15.284] Timer tick, asking Buildbucket for the build status
[08:27:15.418] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:15.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:139:0) after 1m0s
[08:28:15.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:139:0)
[08:28:15.452] Timer tick, asking Buildbucket for the build status
[08:28:15.566] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:15.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:140:0) after 1m0s
[08:29:15.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:140:0)
[08:29:15.617] Timer tick, asking Buildbucket for the build status
[08:29:15.715] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:15.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:141:0) after 1m0s
[08:30:15.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:141:0)
[08:30:15.754] Timer tick, asking Buildbucket for the build status
[08:30:15.905] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:15.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:142:0) after 1m0s
[08:31:15.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:142:0)
[08:31:15.970] Timer tick, asking Buildbucket for the build status
[08:31:16.387] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:16.387] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:143:0) after 1m0s
[08:32:16.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:143:0)
[08:32:16.500] Timer tick, asking Buildbucket for the build status
[08:32:16.618] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:16.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:144:0) after 1m0s
[08:33:16.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:144:0)
[08:33:16.646] Timer tick, asking Buildbucket for the build status
[08:33:16.714] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:16.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:145:0) after 1m0s
[08:34:16.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:145:0)
[08:34:16.790] Timer tick, asking Buildbucket for the build status
[08:34:16.967] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:16.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:146:0) after 1m0s
[08:35:17.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:146:0)
[08:35:17.033] Timer tick, asking Buildbucket for the build status
[08:35:17.217] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:17.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:147:0) after 1m0s
[08:36:17.290] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:147:0)
[08:36:17.290] Timer tick, asking Buildbucket for the build status
[08:36:17.352] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:17.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:148:0) after 1m0s
[08:37:17.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:148:0)
[08:37:17.461] Timer tick, asking Buildbucket for the build status
[08:37:17.564] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:17.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:149:0) after 1m0s
[08:38:17.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:149:0)
[08:38:17.588] Timer tick, asking Buildbucket for the build status
[08:38:17.738] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:17.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:150:0) after 1m0s
[08:39:17.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:150:0)
[08:39:17.719] Timer tick, asking Buildbucket for the build status
[08:39:17.832] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:17.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:151:0) after 1m0s
[08:40:17.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:151:0)
[08:40:17.958] Timer tick, asking Buildbucket for the build status
[08:40:18.021] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:18.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:152:0) after 1m0s
[08:41:18.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:152:0)
[08:41:18.063] Timer tick, asking Buildbucket for the build status
[08:41:18.170] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:18.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:153:0) after 1m0s
[08:42:18.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:153:0)
[08:42:18.232] Timer tick, asking Buildbucket for the build status
[08:42:18.492] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:18.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:154:0) after 1m0s
[08:43:18.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:154:0)
[08:43:18.522] Timer tick, asking Buildbucket for the build status
[08:43:18.677] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:18.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:155:0) after 1m0s
[08:44:18.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:155:0)
[08:44:18.736] Timer tick, asking Buildbucket for the build status
[08:44:20.383] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:20.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:156:0) after 1m0s
[08:45:20.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:156:0)
[08:45:20.408] Timer tick, asking Buildbucket for the build status
[08:45:20.571] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:20.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:157:0) after 1m0s
[08:46:20.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:157:0)
[08:46:20.672] Timer tick, asking Buildbucket for the build status
[08:46:22.584] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:22.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:158:0) after 1m0s
[08:47:22.606] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:158:0)
[08:47:22.606] Timer tick, asking Buildbucket for the build status
[08:47:22.768] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:22.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:159:0) after 1m0s
[08:48:22.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:159:0)
[08:48:22.830] Timer tick, asking Buildbucket for the build status
[08:48:22.880] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:22.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:160:0) after 1m0s
[08:49:22.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:160:0)
[08:49:22.907] Timer tick, asking Buildbucket for the build status
[08:49:24.404] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:24.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:161:0) after 1m0s
[08:50:24.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:161:0)
[08:50:24.446] Timer tick, asking Buildbucket for the build status
[08:50:24.741] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:24.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:162:0) after 1m0s
[08:51:24.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:162:0)
[08:51:24.788] Timer tick, asking Buildbucket for the build status
[08:51:24.934] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:24.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:163:0) after 1m0s
[08:52:25.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:163:0)
[08:52:25.001] Timer tick, asking Buildbucket for the build status
[08:52:25.082] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:25.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:164:0) after 1m0s
[08:53:25.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:164:0)
[08:53:25.124] Timer tick, asking Buildbucket for the build status
[08:53:25.259] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:25.259] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:165:0) after 1m0s
[08:54:25.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:165:0)
[08:54:25.266] Timer tick, asking Buildbucket for the build status
[08:54:25.559] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:25.560] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:166:0) after 1m0s
[08:55:25.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:166:0)
[08:55:25.617] Timer tick, asking Buildbucket for the build status
[08:55:25.750] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:25.750] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:167:0) after 1m0s
[08:56:25.825] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:167:0)
[08:56:25.825] Timer tick, asking Buildbucket for the build status
[08:56:26.011] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:26.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:168:0) after 1m0s
[08:57:26.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:168:0)
[08:57:26.038] Timer tick, asking Buildbucket for the build status
[08:57:26.172] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:26.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:169:0) after 1m0s
[08:58:26.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:169:0)
[08:58:26.187] Timer tick, asking Buildbucket for the build status
[08:58:26.305] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:26.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:170:0) after 1m0s
[08:59:26.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:170:0)
[08:59:26.333] Timer tick, asking Buildbucket for the build status
[08:59:26.994] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:26.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:171:0) after 1m0s
[09:00:27.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:171:0)
[09:00:27.047] Timer tick, asking Buildbucket for the build status
[09:00:28.148] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:28.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:172:0) after 1m0s
[09:01:28.155] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:172:0)
[09:01:28.155] Timer tick, asking Buildbucket for the build status
[09:01:28.376] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:28.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:173:0) after 1m0s
[09:02:28.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:173:0)
[09:02:28.471] Timer tick, asking Buildbucket for the build status
[09:02:28.576] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:28.576] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:174:0) after 1m0s
[09:03:28.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:174:0)
[09:03:28.601] Timer tick, asking Buildbucket for the build status
[09:03:28.664] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:28.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:175:0) after 1m0s
[09:04:28.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:175:0)
[09:04:28.705] Timer tick, asking Buildbucket for the build status
[09:04:28.811] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:28.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:176:0) after 1m0s
[09:05:28.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:176:0)
[09:05:28.880] Timer tick, asking Buildbucket for the build status
[09:05:29.037] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:29.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:177:0) after 1m0s
[09:06:29.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:177:0)
[09:06:29.078] Timer tick, asking Buildbucket for the build status
[09:06:29.801] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:29.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:178:0) after 1m0s
[09:07:29.832] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:178:0)
[09:07:29.832] Timer tick, asking Buildbucket for the build status
[09:07:29.944] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:29.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:179:0) after 1m0s
[09:08:29.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:179:0)
[09:08:29.998] Timer tick, asking Buildbucket for the build status
[09:08:30.143] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:30.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:180:0) after 1m0s
[09:09:30.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:180:0)
[09:09:30.176] Timer tick, asking Buildbucket for the build status
[09:09:30.325] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:30.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:181:0) after 1m0s
[09:10:30.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:181:0)
[09:10:30.358] Timer tick, asking Buildbucket for the build status
[09:10:30.423] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:30.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:182:0) after 1m0s
[09:11:30.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:182:0)
[09:11:30.411] Timer tick, asking Buildbucket for the build status
[09:11:30.773] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:30.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:183:0) after 1m0s
[09:12:30.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:183:0)
[09:12:30.893] Timer tick, asking Buildbucket for the build status
[09:12:31.400] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:31.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:184:0) after 1m0s
[09:13:31.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:184:0)
[09:13:31.472] Timer tick, asking Buildbucket for the build status
[09:13:31.681] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:31.681] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:185:0) after 1m0s
[09:14:31.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:185:0)
[09:14:31.700] Timer tick, asking Buildbucket for the build status
[09:14:32.683] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:32.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:186:0) after 1m0s
[09:15:32.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:186:0)
[09:15:32.720] Timer tick, asking Buildbucket for the build status
[09:15:33.133] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:33.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:187:0) after 1m0s
[09:16:33.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:187:0)
[09:16:33.145] Timer tick, asking Buildbucket for the build status
[09:16:33.312] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:33.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:188:0) after 1m0s
[09:17:33.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:188:0)
[09:17:33.408] Timer tick, asking Buildbucket for the build status
[09:17:33.487] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:33.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:189:0) after 1m0s
[09:18:33.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:189:0)
[09:18:33.504] Timer tick, asking Buildbucket for the build status
[09:18:33.983] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:33.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:190:0) after 1m0s
[09:19:34.050] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:190:0)
[09:19:34.050] Timer tick, asking Buildbucket for the build status
[09:19:34.288] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:34.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:191:0) after 1m0s
[09:20:34.314] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:191:0)
[09:20:34.314] Timer tick, asking Buildbucket for the build status
[09:20:34.571] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:34.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:192:0) after 1m0s
[09:21:34.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:192:0)
[09:21:34.616] Timer tick, asking Buildbucket for the build status
[09:21:34.814] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:34.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:193:0) after 1m0s
[09:22:34.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:193:0)
[09:22:34.846] Timer tick, asking Buildbucket for the build status
[09:22:35.004] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:35.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:194:0) after 1m0s
[09:23:35.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:194:0)
[09:23:35.028] Timer tick, asking Buildbucket for the build status
[09:23:35.164] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:35.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:195:0) after 1m0s
[09:24:35.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:195:0)
[09:24:35.189] Timer tick, asking Buildbucket for the build status
[09:24:35.527] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:35.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:196:0) after 1m0s
[09:25:35.553] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:196:0)
[09:25:35.553] Timer tick, asking Buildbucket for the build status
[09:25:36.011] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:36.011] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:197:0) after 1m0s
[09:26:36.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:197:0)
[09:26:36.044] Timer tick, asking Buildbucket for the build status
[09:26:36.806] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:36.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:198:0) after 1m0s
[09:27:36.859] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:198:0)
[09:27:36.859] Timer tick, asking Buildbucket for the build status
[09:27:37.088] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:37.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:199:0) after 1m0s
[09:28:37.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:199:0)
[09:28:37.072] Timer tick, asking Buildbucket for the build status
[09:28:37.263] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:37.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:200:0) after 1m0s
[09:29:37.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:200:0)
[09:29:37.330] Timer tick, asking Buildbucket for the build status
[09:29:37.456] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:37.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:201:0) after 1m0s
[09:30:37.512] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:201:0)
[09:30:37.512] Timer tick, asking Buildbucket for the build status
[09:30:37.719] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:37.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:202:0) after 1m0s
[09:31:37.836] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:202:0)
[09:31:37.836] Timer tick, asking Buildbucket for the build status
[09:31:39.247] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:39.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:203:0) after 1m0s
[09:32:39.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:203:0)
[09:32:39.251] Timer tick, asking Buildbucket for the build status
[09:32:39.516] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:39.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:204:0) after 1m0s
[09:33:39.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:204:0)
[09:33:39.636] Timer tick, asking Buildbucket for the build status
[09:33:39.702] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:39.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:205:0) after 1m0s
[09:34:39.833] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:205:0)
[09:34:39.833] Timer tick, asking Buildbucket for the build status
[09:34:39.935] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:39.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:206:0) after 1m0s
[09:35:40.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:206:0)
[09:35:40.067] Timer tick, asking Buildbucket for the build status
[09:35:40.402] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:40.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:207:0) after 1m0s
[09:36:40.472] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:207:0)
[09:36:40.472] Timer tick, asking Buildbucket for the build status
[09:36:40.590] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:40.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:208:0) after 1m0s
[09:37:40.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:208:0)
[09:37:40.617] Timer tick, asking Buildbucket for the build status
[09:37:40.764] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:40.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:209:0) after 1m0s
[09:38:40.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:209:0)
[09:38:40.875] Timer tick, asking Buildbucket for the build status
[09:38:41.029] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:41.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:210:0) after 1m0s
[09:39:41.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:210:0)
[09:39:41.118] Timer tick, asking Buildbucket for the build status
[09:39:41.325] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:41.325] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:211:0) after 1m0s
[09:40:41.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:211:0)
[09:40:41.349] Timer tick, asking Buildbucket for the build status
[09:40:41.426] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:41.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:212:0) after 1m0s
[09:41:41.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:212:0)
[09:41:41.487] Timer tick, asking Buildbucket for the build status
[09:41:41.619] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:41.619] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:213:0) after 1m0s
[09:42:41.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:213:0)
[09:42:41.594] Timer tick, asking Buildbucket for the build status
[09:42:42.020] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:42.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:214:0) after 1m0s
[09:43:42.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:214:0)
[09:43:42.195] Timer tick, asking Buildbucket for the build status
[09:43:42.285] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:42.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:215:0) after 1m0s
[09:44:42.414] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:215:0)
[09:44:42.414] Timer tick, asking Buildbucket for the build status
[09:44:42.817] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:42.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:216:0) after 1m0s
[09:45:43.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:216:0)
[09:45:43.146] Timer tick, asking Buildbucket for the build status
[09:45:43.371] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:43.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:217:0) after 1m0s
[09:46:43.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:217:0)
[09:46:43.529] Timer tick, asking Buildbucket for the build status
[09:46:43.612] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:43.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:218:0) after 1m0s
[09:47:43.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:218:0)
[09:47:43.735] Timer tick, asking Buildbucket for the build status
[09:47:43.945] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:43.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:219:0) after 1m0s
[09:48:43.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:219:0)
[09:48:43.989] Timer tick, asking Buildbucket for the build status
[09:48:44.155] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:44.155] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:220:0) after 1m0s
[09:49:44.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:220:0)
[09:49:44.220] Timer tick, asking Buildbucket for the build status
[09:49:44.312] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:44.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:221:0) after 1m0s
[09:50:44.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:221:0)
[09:50:44.448] Timer tick, asking Buildbucket for the build status
[09:50:44.707] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:44.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:222:0) after 1m0s
[09:51:44.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:222:0)
[09:51:44.722] Timer tick, asking Buildbucket for the build status
[09:51:44.845] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:44.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:223:0) after 1m0s
[09:52:44.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:223:0)
[09:52:44.937] Timer tick, asking Buildbucket for the build status
[09:52:45.550] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:45.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:224:0) after 1m0s
[09:53:45.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:224:0)
[09:53:45.634] Timer tick, asking Buildbucket for the build status
[09:53:45.863] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:45.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:225:0) after 1m0s
[09:54:45.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:225:0)
[09:54:45.894] Timer tick, asking Buildbucket for the build status
[09:54:46.122] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:46.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:226:0) after 1m0s
[09:55:46.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:226:0)
[09:55:46.151] Timer tick, asking Buildbucket for the build status
[09:55:46.304] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:46.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:227:0) after 1m0s
[09:56:46.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:227:0)
[09:56:46.327] Timer tick, asking Buildbucket for the build status
[09:56:46.434] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:46.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:228:0) after 1m0s
[09:57:46.468] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:228:0)
[09:57:46.468] Timer tick, asking Buildbucket for the build status
[09:57:46.794] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:46.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:229:0) after 1m0s
[09:58:46.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:229:0)
[09:58:46.849] Timer tick, asking Buildbucket for the build status
[09:58:47.067] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:47.067] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:230:0) after 1m0s
[09:59:47.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:230:0)
[09:59:47.089] Timer tick, asking Buildbucket for the build status
[09:59:47.200] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:47.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:231:0) after 1m0s
[10:00:47.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:231:0)
[10:00:47.294] Timer tick, asking Buildbucket for the build status
[10:00:47.501] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:47.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:232:0) after 1m0s
[10:01:47.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:232:0)
[10:01:47.581] Timer tick, asking Buildbucket for the build status
[10:01:47.739] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:47.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:233:0) after 1m0s
[10:02:47.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:233:0)
[10:02:47.800] Timer tick, asking Buildbucket for the build status
[10:02:47.890] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:47.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:234:0) after 1m0s
[10:03:47.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:234:0)
[10:03:47.949] Timer tick, asking Buildbucket for the build status
[10:03:48.138] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:48.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:235:0) after 1m0s
[10:04:48.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:235:0)
[10:04:48.164] Timer tick, asking Buildbucket for the build status
[10:04:48.533] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:48.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:236:0) after 1m0s
[10:05:48.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:236:0)
[10:05:48.695] Timer tick, asking Buildbucket for the build status
[10:05:48.829] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:48.829] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:237:0) after 1m0s
[10:06:48.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:237:0)
[10:06:48.867] Timer tick, asking Buildbucket for the build status
[10:06:48.977] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:48.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:238:0) after 1m0s
[10:07:49.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:238:0)
[10:07:49.025] Timer tick, asking Buildbucket for the build status
[10:07:49.344] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:49.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:239:0) after 1m0s
[10:08:49.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:239:0)
[10:08:49.428] Timer tick, asking Buildbucket for the build status
[10:08:49.531] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:49.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:240:0) after 1m0s
[10:09:49.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:240:0)
[10:09:49.596] Timer tick, asking Buildbucket for the build status
[10:09:49.833] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:49.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:241:0) after 1m0s
[10:10:49.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:241:0)
[10:10:49.863] Timer tick, asking Buildbucket for the build status
[10:10:50.021] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:50.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:242:0) after 1m0s
[10:11:50.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:242:0)
[10:11:50.053] Timer tick, asking Buildbucket for the build status
[10:11:50.432] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:50.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:243:0) after 1m0s
[10:12:50.467] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:243:0)
[10:12:50.467] Timer tick, asking Buildbucket for the build status
[10:12:50.641] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:50.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:244:0) after 1m0s
[10:13:50.709] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:244:0)
[10:13:50.709] Timer tick, asking Buildbucket for the build status
[10:13:50.856] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:50.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:245:0) after 1m0s
[10:14:50.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:245:0)
[10:14:50.924] Timer tick, asking Buildbucket for the build status
[10:14:51.071] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:51.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:246:0) after 1m0s
[10:15:51.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:246:0)
[10:15:51.086] Timer tick, asking Buildbucket for the build status
[10:15:51.211] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:247:0) after 1m0s
[10:16:51.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:247:0)
[10:16:51.252] Timer tick, asking Buildbucket for the build status
[10:16:51.402] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:51.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:248:0) after 1m0s
[10:17:51.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:248:0)
[10:17:51.464] Timer tick, asking Buildbucket for the build status
[10:17:51.720] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:51.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:249:0) after 1m0s
[10:18:51.823] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:249:0)
[10:18:51.823] Timer tick, asking Buildbucket for the build status
[10:18:51.944] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:51.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:250:0) after 1m0s
[10:19:52.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:250:0)
[10:19:52.018] Timer tick, asking Buildbucket for the build status
[10:19:52.135] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:52.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:251:0) after 1m0s
[10:20:52.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:251:0)
[10:20:52.164] Timer tick, asking Buildbucket for the build status
[10:20:52.302] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:52.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:252:0) after 1m0s
[10:21:52.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:252:0)
[10:21:52.349] Timer tick, asking Buildbucket for the build status
[10:21:52.413] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:52.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:253:0) after 1m0s
[10:22:52.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:253:0)
[10:22:52.429] Timer tick, asking Buildbucket for the build status
[10:22:52.585] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:52.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:254:0) after 1m0s
[10:23:52.695] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:254:0)
[10:23:52.695] Timer tick, asking Buildbucket for the build status
[10:23:52.843] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:52.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:255:0) after 1m0s
[10:24:53.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:255:0)
[10:24:53.055] Timer tick, asking Buildbucket for the build status
[10:24:53.158] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:53.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:256:0) after 1m0s
[10:25:53.173] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:256:0)
[10:25:53.173] Timer tick, asking Buildbucket for the build status
[10:25:53.434] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:53.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:257:0) after 1m0s
[10:26:53.452] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:257:0)
[10:26:53.452] Timer tick, asking Buildbucket for the build status
[10:26:53.623] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:53.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:258:0) after 1m0s
[10:27:53.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:258:0)
[10:27:53.704] Timer tick, asking Buildbucket for the build status
[10:27:53.794] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:53.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:259:0) after 1m0s
[10:28:53.851] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:259:0)
[10:28:53.851] Timer tick, asking Buildbucket for the build status
[10:28:54.154] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:54.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:260:0) after 1m0s
[10:29:54.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:260:0)
[10:29:54.264] Timer tick, asking Buildbucket for the build status
[10:29:54.483] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:54.483] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:261:0) after 1m0s
[10:30:54.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:261:0)
[10:30:54.554] Timer tick, asking Buildbucket for the build status
[10:30:54.710] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:54.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:262:0) after 1m0s
[10:31:54.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:262:0)
[10:31:54.776] Timer tick, asking Buildbucket for the build status
[10:31:54.947] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:54.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:263:0) after 1m0s
[10:32:55.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:263:0)
[10:32:55.023] Timer tick, asking Buildbucket for the build status
[10:32:55.174] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:55.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:264:0) after 1m0s
[10:33:55.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:264:0)
[10:33:55.189] Timer tick, asking Buildbucket for the build status
[10:33:55.330] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:55.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:265:0) after 1m0s
[10:34:55.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:265:0)
[10:34:55.387] Timer tick, asking Buildbucket for the build status
[10:34:55.455] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:55.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:266:0) after 1m0s
[10:35:55.595] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:266:0)
[10:35:55.595] Timer tick, asking Buildbucket for the build status
[10:35:57.199] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:57.199] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:267:0) after 1m0s
[10:36:57.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:267:0)
[10:36:57.258] Timer tick, asking Buildbucket for the build status
[10:36:57.389] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:57.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:268:0) after 1m0s
[10:37:57.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:268:0)
[10:37:57.497] Timer tick, asking Buildbucket for the build status
[10:37:57.635] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:57.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:269:0) after 1m0s
[10:38:57.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:269:0)
[10:38:57.668] Timer tick, asking Buildbucket for the build status
[10:38:57.915] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:57.915] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:270:0) after 1m0s
[10:39:57.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:270:0)
[10:39:57.948] Timer tick, asking Buildbucket for the build status
[10:39:58.198] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:58.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:271:0) after 1m0s
[10:40:58.281] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:271:0)
[10:40:58.281] Timer tick, asking Buildbucket for the build status
[10:40:58.406] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:58.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:272:0) after 1m0s
[10:41:58.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:272:0)
[10:41:58.477] Timer tick, asking Buildbucket for the build status
[10:41:58.605] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:58.605] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:273:0) after 1m0s
[10:42:58.697] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:273:0)
[10:42:58.697] Timer tick, asking Buildbucket for the build status
[10:42:59.212] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:59.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:274:0) after 1m0s
[10:43:59.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:274:0)
[10:43:59.261] Timer tick, asking Buildbucket for the build status
[10:43:59.371] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:59.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:275:0) after 1m0s
[10:44:59.403] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:275:0)
[10:44:59.403] Timer tick, asking Buildbucket for the build status
[10:44:59.527] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:59.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:276:0) after 1m0s
[10:45:59.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:276:0)
[10:45:59.550] Timer tick, asking Buildbucket for the build status
[10:45:59.751] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:59.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:277:0) after 1m0s
[10:46:59.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:277:0)
[10:46:59.780] Timer tick, asking Buildbucket for the build status
[10:47:00.102] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:00.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:278:0) after 1m0s
[10:48:00.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:278:0)
[10:48:00.125] Timer tick, asking Buildbucket for the build status
[10:48:00.261] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:00.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:279:0) after 1m0s
[10:49:00.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:279:0)
[10:49:00.324] Timer tick, asking Buildbucket for the build status
[10:49:00.493] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:00.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:280:0) after 1m0s
[10:50:00.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:280:0)
[10:50:00.557] Timer tick, asking Buildbucket for the build status
[10:50:00.954] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:00.954] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:281:0) after 1m0s
[10:51:00.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:281:0)
[10:51:00.998] Timer tick, asking Buildbucket for the build status
[10:51:01.136] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:01.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:282:0) after 1m0s
[10:52:01.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:282:0)
[10:52:01.163] Timer tick, asking Buildbucket for the build status
[10:52:01.236] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:01.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:283:0) after 1m0s
[10:53:01.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:283:0)
[10:53:01.367] Timer tick, asking Buildbucket for the build status
[10:53:01.930] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:01.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:284:0) after 1m0s
[10:54:02.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:284:0)
[10:54:02.084] Timer tick, asking Buildbucket for the build status
[10:54:02.248] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:02.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:285:0) after 1m0s
[10:55:02.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:285:0)
[10:55:02.309] Timer tick, asking Buildbucket for the build status
[10:55:02.487] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:02.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:286:0) after 1m0s
[10:56:02.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:286:0)
[10:56:02.517] Timer tick, asking Buildbucket for the build status
[10:56:02.660] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:02.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:287:0) after 1m0s
[10:57:02.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:287:0)
[10:57:02.766] Timer tick, asking Buildbucket for the build status
[10:57:02.931] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:02.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:288:0) after 1m0s
[10:58:03.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:288:0)
[10:58:03.141] Timer tick, asking Buildbucket for the build status
[10:58:03.298] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:03.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:289:0) after 1m0s
[10:59:03.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:289:0)
[10:59:03.335] Timer tick, asking Buildbucket for the build status
[10:59:03.471] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:03.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:290:0) after 1m0s
[11:00:03.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:290:0)
[11:00:03.502] Timer tick, asking Buildbucket for the build status
[11:00:03.821] Build 8884969068633362080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:03.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9050399130576859168:291:0) after 1m0s
[11:00:27.646] Received PubSub notification, asking Buildbucket for the build status
[11:00:27.761] Build 8884969068633362080: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:00:27.761] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1585047626606608",
    "created_by": "project:nacl",
    "created_ts": "1585030219388538",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884969068633362080",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "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\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-wmbs\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5093, \"got_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-wmbs\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4895-ce889b2\"], \"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": "1585035190105563",
    "status": "COMPLETED",
    "status_changed_ts": "1585035190593671",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5093",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050399130576859168",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884969068633362080/+/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:4b23fb68eed07610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585047626890762",
    "url": "https://ci.chromium.org/b/8884969068633362080",
    "utcnow_ts": "1585047627749994"
  }
}
[11:00:27.761] Invocation finished in 4h50m9.610626616s with status FAILED