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

• 99673869a3cd8731d924bd32fa486feebfdc6c4f (6 years ago)

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

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

[23:17:42.204] New invocation is queued and will start shortly
[23:17:43.413] Starting the invocation (attempt 1)
[23:17:43.445] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:17:43.445] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:17:43.445] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9097897899491860512",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"99673869a3cd8731d924bd32fa486feebfdc6c4f\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9097897899491860512",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/99673869a3cd8731d924bd32fa486feebfdc6c4f",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/99673869a3cd8731d924bd32fa486feebfdc6c4f",
    "gitiles_ref:refs/heads/master"
  ]
}
[23:17:44.963] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539731863505022",
    "experimental": true,
    "id": "8932467837451516976",
    "lease_expiration_ts": "1539850664557857",
    "lease_key": "523017217",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1539731864846609",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9097897899491860512",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
      "swarming_tag:buildbucket_bucket:luci.nacl.toolchain",
      "swarming_tag:buildbucket_build_id:8932467837451516976",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "swarming_tag:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8932467837451516976/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:60",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9097897899491860512",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:82431e273d2f347205266ff4363205d6de25e0ad",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4097fd42fb1e8310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1539731864846719",
    "url": "https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
    "utcnow_ts": "1539731864934951"
  }
}
[23:17:44.963] Task URL: https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8
[23:17:44.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:2:0) after 1m0s
[23:18:32.409] Received PubSub notification, asking Buildbucket for the build status
[23:18:32.458] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:44.981] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:2:0)
[23:18:44.981] Timer tick, asking Buildbucket for the build status
[23:18:45.026] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:45.026] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:4:0) after 1m0s
[23:19:45.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:4:0)
[23:19:45.062] Timer tick, asking Buildbucket for the build status
[23:19:45.111] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:45.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:5:0) after 1m0s
[23:20:45.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:5:0)
[23:20:45.129] Timer tick, asking Buildbucket for the build status
[23:20:45.362] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:45.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:6:0) after 1m0s
[23:21:45.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:6:0)
[23:21:45.384] Timer tick, asking Buildbucket for the build status
[23:21:45.449] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:45.449] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:7:0) after 1m0s
[23:22:45.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:7:0)
[23:22:45.464] Timer tick, asking Buildbucket for the build status
[23:22:45.521] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:45.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:8:0) after 1m0s
[23:23:45.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:8:0)
[23:23:45.534] Timer tick, asking Buildbucket for the build status
[23:23:45.594] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:45.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:9:0) after 1m0s
[23:24:45.611] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:9:0)
[23:24:45.611] Timer tick, asking Buildbucket for the build status
[23:24:45.700] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:45.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:10:0) after 1m0s
[23:25:45.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:10:0)
[23:25:45.717] Timer tick, asking Buildbucket for the build status
[23:25:45.796] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:45.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:11:0) after 1m0s
[23:26:45.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:11:0)
[23:26:45.816] Timer tick, asking Buildbucket for the build status
[23:26:45.867] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:45.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:12:0) after 1m0s
[23:27:45.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:12:0)
[23:27:45.887] Timer tick, asking Buildbucket for the build status
[23:27:45.939] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:45.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:13:0) after 1m0s
[23:28:45.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:13:0)
[23:28:45.955] Timer tick, asking Buildbucket for the build status
[23:28:46.056] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:46.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:14:0) after 1m0s
[23:29:46.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:14:0)
[23:29:46.073] Timer tick, asking Buildbucket for the build status
[23:29:46.145] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:46.145] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:15:0) after 1m0s
[23:30:46.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:15:0)
[23:30:46.162] Timer tick, asking Buildbucket for the build status
[23:30:46.244] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:46.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:16:0) after 1m0s
[23:31:46.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:16:0)
[23:31:46.261] Timer tick, asking Buildbucket for the build status
[23:31:46.308] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:46.308] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:17:0) after 1m0s
[23:32:46.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:17:0)
[23:32:46.326] Timer tick, asking Buildbucket for the build status
[23:32:46.451] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:46.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:18:0) after 1m0s
[23:33:46.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:18:0)
[23:33:46.470] Timer tick, asking Buildbucket for the build status
[23:33:46.541] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:46.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:19:0) after 1m0s
[23:34:46.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:19:0)
[23:34:46.560] Timer tick, asking Buildbucket for the build status
[23:34:46.739] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:46.739] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:20:0) after 1m0s
[23:35:46.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:20:0)
[23:35:46.761] Timer tick, asking Buildbucket for the build status
[23:35:46.848] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:46.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:21:0) after 1m0s
[23:36:46.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:21:0)
[23:36:46.869] Timer tick, asking Buildbucket for the build status
[23:36:46.990] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:46.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:22:0) after 1m0s
[23:37:47.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:22:0)
[23:37:47.007] Timer tick, asking Buildbucket for the build status
[23:37:47.143] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:47.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:23:0) after 1m0s
[23:38:47.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:23:0)
[23:38:47.167] Timer tick, asking Buildbucket for the build status
[23:38:47.224] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:47.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:24:0) after 1m0s
[23:39:47.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:24:0)
[23:39:47.327] Timer tick, asking Buildbucket for the build status
[23:39:47.445] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:47.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:25:0) after 1m0s
[23:40:47.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:25:0)
[23:40:47.462] Timer tick, asking Buildbucket for the build status
[23:40:47.553] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:47.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:26:0) after 1m0s
[23:41:47.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:26:0)
[23:41:47.573] Timer tick, asking Buildbucket for the build status
[23:41:47.623] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:47.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:27:0) after 1m0s
[23:42:47.640] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:27:0)
[23:42:47.640] Timer tick, asking Buildbucket for the build status
[23:42:47.699] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:47.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:28:0) after 1m0s
[23:43:47.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:28:0)
[23:43:47.714] Timer tick, asking Buildbucket for the build status
[23:43:47.768] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:47.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:29:0) after 1m0s
[23:44:47.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:29:0)
[23:44:47.796] Timer tick, asking Buildbucket for the build status
[23:44:47.856] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:47.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:30:0) after 1m0s
[23:45:47.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:30:0)
[23:45:47.876] Timer tick, asking Buildbucket for the build status
[23:45:47.957] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:47.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:31:0) after 1m0s
[23:46:48.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:31:0)
[23:46:48.041] Timer tick, asking Buildbucket for the build status
[23:46:48.108] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:48.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:32:0) after 1m0s
[23:47:48.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:32:0)
[23:47:48.152] Timer tick, asking Buildbucket for the build status
[23:47:48.399] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:48.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:33:0) after 1m0s
[23:48:48.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:33:0)
[23:48:48.419] Timer tick, asking Buildbucket for the build status
[23:48:48.494] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:48.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:34:0) after 1m0s
[23:49:48.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:34:0)
[23:49:48.546] Timer tick, asking Buildbucket for the build status
[23:49:48.649] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:48.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:35:0) after 1m0s
[23:50:48.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:35:0)
[23:50:48.696] Timer tick, asking Buildbucket for the build status
[23:50:48.738] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:48.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:36:0) after 1m0s
[23:51:48.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:36:0)
[23:51:48.772] Timer tick, asking Buildbucket for the build status
[23:51:48.880] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:48.880] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:37:0) after 1m0s
[23:52:48.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:37:0)
[23:52:48.897] Timer tick, asking Buildbucket for the build status
[23:52:48.975] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:48.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:38:0) after 1m0s
[23:53:48.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:38:0)
[23:53:48.996] Timer tick, asking Buildbucket for the build status
[23:53:49.120] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:49.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:39:0) after 1m0s
[23:54:49.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:39:0)
[23:54:49.186] Timer tick, asking Buildbucket for the build status
[23:54:49.424] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:49.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:40:0) after 1m0s
[23:55:49.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:40:0)
[23:55:49.444] Timer tick, asking Buildbucket for the build status
[23:55:49.556] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:49.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:41:0) after 1m0s
[23:56:49.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:41:0)
[23:56:49.589] Timer tick, asking Buildbucket for the build status
[23:56:49.825] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:49.825] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:42:0) after 1m0s
[23:57:49.844] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:42:0)
[23:57:49.844] Timer tick, asking Buildbucket for the build status
[23:57:49.883] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:49.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:43:0) after 1m0s
[23:58:49.945] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:43:0)
[23:58:49.945] Timer tick, asking Buildbucket for the build status
[23:58:50.092] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:50.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:44:0) after 1m0s
[23:59:50.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:44:0)
[23:59:50.109] Timer tick, asking Buildbucket for the build status
[23:59:50.193] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:50.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:45:0) after 1m0s
[00:00:50.211] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:45:0)
[00:00:50.211] Timer tick, asking Buildbucket for the build status
[00:00:50.260] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:50.260] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:46:0) after 1m0s
[00:01:50.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:46:0)
[00:01:50.307] Timer tick, asking Buildbucket for the build status
[00:01:50.392] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:50.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:47:0) after 1m0s
[00:02:50.416] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:47:0)
[00:02:50.416] Timer tick, asking Buildbucket for the build status
[00:02:50.469] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:50.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:48:0) after 1m0s
[00:03:50.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:48:0)
[00:03:50.490] Timer tick, asking Buildbucket for the build status
[00:03:50.565] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:50.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:49:0) after 1m0s
[00:04:50.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:49:0)
[00:04:50.756] Timer tick, asking Buildbucket for the build status
[00:04:50.842] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:50.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:50:0) after 1m0s
[00:05:50.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:50:0)
[00:05:50.863] Timer tick, asking Buildbucket for the build status
[00:05:50.961] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:50.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:51:0) after 1m0s
[00:06:50.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:51:0)
[00:06:50.987] Timer tick, asking Buildbucket for the build status
[00:06:51.151] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:51.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:52:0) after 1m0s
[00:07:51.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:52:0)
[00:07:51.172] Timer tick, asking Buildbucket for the build status
[00:07:51.252] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:51.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:53:0) after 1m0s
[00:08:51.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:53:0)
[00:08:51.275] Timer tick, asking Buildbucket for the build status
[00:08:51.440] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:51.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:54:0) after 1m0s
[00:09:51.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:54:0)
[00:09:51.459] Timer tick, asking Buildbucket for the build status
[00:09:51.532] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:51.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:55:0) after 1m0s
[00:10:51.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:55:0)
[00:10:51.554] Timer tick, asking Buildbucket for the build status
[00:10:51.623] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:51.623] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:56:0) after 1m0s
[00:11:51.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:56:0)
[00:11:51.702] Timer tick, asking Buildbucket for the build status
[00:11:51.817] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:51.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:57:0) after 1m0s
[00:12:51.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:57:0)
[00:12:51.892] Timer tick, asking Buildbucket for the build status
[00:12:51.942] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:51.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:58:0) after 1m0s
[00:13:51.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:58:0)
[00:13:51.963] Timer tick, asking Buildbucket for the build status
[00:13:52.034] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:52.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:59:0) after 1m0s
[00:14:52.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:59:0)
[00:14:52.055] Timer tick, asking Buildbucket for the build status
[00:14:52.247] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:52.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:60:0) after 1m0s
[00:15:52.270] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:60:0)
[00:15:52.270] Timer tick, asking Buildbucket for the build status
[00:15:52.324] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:52.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:61:0) after 1m0s
[00:16:52.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:61:0)
[00:16:52.353] Timer tick, asking Buildbucket for the build status
[00:16:52.738] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:52.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:62:0) after 1m0s
[00:17:52.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:62:0)
[00:17:52.770] Timer tick, asking Buildbucket for the build status
[00:17:52.848] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:52.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:63:0) after 1m0s
[00:18:52.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:63:0)
[00:18:52.867] Timer tick, asking Buildbucket for the build status
[00:18:52.953] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:52.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:64:0) after 1m0s
[00:19:52.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:64:0)
[00:19:52.971] Timer tick, asking Buildbucket for the build status
[00:19:53.051] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:53.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:65:0) after 1m0s
[00:20:53.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:65:0)
[00:20:53.076] Timer tick, asking Buildbucket for the build status
[00:20:53.128] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:53.128] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:66:0) after 1m0s
[00:21:53.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:66:0)
[00:21:53.152] Timer tick, asking Buildbucket for the build status
[00:21:53.238] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:53.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:67:0) after 1m0s
[00:22:53.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:67:0)
[00:22:53.275] Timer tick, asking Buildbucket for the build status
[00:22:53.352] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:53.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:68:0) after 1m0s
[00:23:53.374] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:68:0)
[00:23:53.374] Timer tick, asking Buildbucket for the build status
[00:23:53.617] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:53.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:69:0) after 1m0s
[00:24:53.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:69:0)
[00:24:53.652] Timer tick, asking Buildbucket for the build status
[00:24:53.817] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:53.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:70:0) after 1m0s
[00:25:53.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:70:0)
[00:25:53.840] Timer tick, asking Buildbucket for the build status
[00:25:54.030] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:54.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:71:0) after 1m0s
[00:26:54.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:71:0)
[00:26:54.054] Timer tick, asking Buildbucket for the build status
[00:26:54.118] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:54.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:72:0) after 1m0s
[00:27:54.169] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:72:0)
[00:27:54.169] Timer tick, asking Buildbucket for the build status
[00:27:54.410] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:54.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:73:0) after 1m0s
[00:28:54.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:73:0)
[00:28:54.431] Timer tick, asking Buildbucket for the build status
[00:28:54.520] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:54.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:74:0) after 1m0s
[00:29:54.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:74:0)
[00:29:54.542] Timer tick, asking Buildbucket for the build status
[00:29:54.641] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:54.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:75:0) after 1m0s
[00:30:54.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:75:0)
[00:30:54.663] Timer tick, asking Buildbucket for the build status
[00:30:54.721] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:54.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:76:0) after 1m0s
[00:31:54.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:76:0)
[00:31:54.752] Timer tick, asking Buildbucket for the build status
[00:31:54.814] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:54.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:77:0) after 1m0s
[00:32:54.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:77:0)
[00:32:54.837] Timer tick, asking Buildbucket for the build status
[00:32:54.977] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:54.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:78:0) after 1m0s
[00:33:55.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:78:0)
[00:33:55.019] Timer tick, asking Buildbucket for the build status
[00:33:55.111] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:55.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:79:0) after 1m0s
[00:34:55.216] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:79:0)
[00:34:55.216] Timer tick, asking Buildbucket for the build status
[00:34:55.378] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:55.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:80:0) after 1m0s
[00:35:55.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:80:0)
[00:35:55.411] Timer tick, asking Buildbucket for the build status
[00:35:55.487] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:55.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:81:0) after 1m0s
[00:36:55.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:81:0)
[00:36:55.506] Timer tick, asking Buildbucket for the build status
[00:36:55.564] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:55.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:82:0) after 1m0s
[00:37:55.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:82:0)
[00:37:55.584] Timer tick, asking Buildbucket for the build status
[00:37:55.650] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:55.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:83:0) after 1m0s
[00:38:55.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:83:0)
[00:38:55.672] Timer tick, asking Buildbucket for the build status
[00:38:55.856] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:55.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:84:0) after 1m0s
[00:39:55.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:84:0)
[00:39:55.892] Timer tick, asking Buildbucket for the build status
[00:39:55.956] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:55.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:85:0) after 1m0s
[00:40:56.032] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:85:0)
[00:40:56.032] Timer tick, asking Buildbucket for the build status
[00:40:56.107] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:56.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:86:0) after 1m0s
[00:41:56.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:86:0)
[00:41:56.127] Timer tick, asking Buildbucket for the build status
[00:41:56.222] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:56.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:87:0) after 1m0s
[00:42:56.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:87:0)
[00:42:56.384] Timer tick, asking Buildbucket for the build status
[00:42:56.489] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:56.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:88:0) after 1m0s
[00:43:56.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:88:0)
[00:43:56.510] Timer tick, asking Buildbucket for the build status
[00:43:56.661] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:56.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:89:0) after 1m0s
[00:44:56.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:89:0)
[00:44:56.682] Timer tick, asking Buildbucket for the build status
[00:44:56.766] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:56.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:90:0) after 1m0s
[00:45:56.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:90:0)
[00:45:56.793] Timer tick, asking Buildbucket for the build status
[00:45:56.855] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:56.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:91:0) after 1m0s
[00:46:56.878] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:91:0)
[00:46:56.878] Timer tick, asking Buildbucket for the build status
[00:46:56.943] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:56.943] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:92:0) after 1m0s
[00:47:56.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:92:0)
[00:47:56.977] Timer tick, asking Buildbucket for the build status
[00:47:57.333] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:57.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:93:0) after 1m0s
[00:48:57.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:93:0)
[00:48:57.353] Timer tick, asking Buildbucket for the build status
[00:48:57.535] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:57.535] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:94:0) after 1m0s
[00:49:57.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:94:0)
[00:49:57.555] Timer tick, asking Buildbucket for the build status
[00:49:57.662] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:57.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:95:0) after 1m0s
[00:50:57.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:95:0)
[00:50:57.689] Timer tick, asking Buildbucket for the build status
[00:50:57.759] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:57.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:96:0) after 1m0s
[00:51:57.780] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:96:0)
[00:51:57.780] Timer tick, asking Buildbucket for the build status
[00:51:59.396] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:59.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:97:0) after 1m0s
[00:52:59.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:97:0)
[00:52:59.425] Timer tick, asking Buildbucket for the build status
[00:52:59.520] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:59.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:98:0) after 1m0s
[00:53:59.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:98:0)
[00:53:59.566] Timer tick, asking Buildbucket for the build status
[00:53:59.655] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:59.655] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:99:0) after 1m0s
[00:54:59.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:99:0)
[00:54:59.680] Timer tick, asking Buildbucket for the build status
[00:54:59.714] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:59.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:100:0) after 1m0s
[00:55:59.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:100:0)
[00:55:59.733] Timer tick, asking Buildbucket for the build status
[00:55:59.843] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:59.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:101:0) after 1m0s
[00:56:59.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:101:0)
[00:56:59.863] Timer tick, asking Buildbucket for the build status
[00:56:59.988] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:59.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:102:0) after 1m0s
[00:58:00.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:102:0)
[00:58:00.020] Timer tick, asking Buildbucket for the build status
[00:58:00.129] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:00.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:103:0) after 1m0s
[00:59:00.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:103:0)
[00:59:00.159] Timer tick, asking Buildbucket for the build status
[00:59:00.333] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:00.333] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:104:0) after 1m0s
[01:00:00.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:104:0)
[01:00:00.351] Timer tick, asking Buildbucket for the build status
[01:00:00.475] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:00.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:105:0) after 1m0s
[01:01:00.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:105:0)
[01:01:00.495] Timer tick, asking Buildbucket for the build status
[01:01:00.694] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:00.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:106:0) after 1m0s
[01:02:00.773] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:106:0)
[01:02:00.773] Timer tick, asking Buildbucket for the build status
[01:02:00.832] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:00.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:107:0) after 1m0s
[01:03:00.921] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:107:0)
[01:03:00.921] Timer tick, asking Buildbucket for the build status
[01:03:01.002] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:01.002] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:108:0) after 1m0s
[01:04:01.037] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:108:0)
[01:04:01.037] Timer tick, asking Buildbucket for the build status
[01:04:01.389] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:01.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:109:0) after 1m0s
[01:05:01.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:109:0)
[01:05:01.413] Timer tick, asking Buildbucket for the build status
[01:05:01.474] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:01.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:110:0) after 1m0s
[01:06:01.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:110:0)
[01:06:01.493] Timer tick, asking Buildbucket for the build status
[01:06:01.778] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:01.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:111:0) after 1m0s
[01:07:01.815] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:111:0)
[01:07:01.815] Timer tick, asking Buildbucket for the build status
[01:07:02.077] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:02.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:112:0) after 1m0s
[01:08:02.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:112:0)
[01:08:02.112] Timer tick, asking Buildbucket for the build status
[01:08:02.192] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:02.192] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:113:0) after 1m0s
[01:09:02.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:113:0)
[01:09:02.344] Timer tick, asking Buildbucket for the build status
[01:09:02.443] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:02.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:114:0) after 1m0s
[01:10:02.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:114:0)
[01:10:02.464] Timer tick, asking Buildbucket for the build status
[01:10:02.546] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:02.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:115:0) after 1m0s
[01:11:02.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:115:0)
[01:11:02.565] Timer tick, asking Buildbucket for the build status
[01:11:02.896] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:02.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:116:0) after 1m0s
[01:12:02.953] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:116:0)
[01:12:02.953] Timer tick, asking Buildbucket for the build status
[01:12:03.550] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:03.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:117:0) after 1m0s
[01:13:03.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:117:0)
[01:13:03.588] Timer tick, asking Buildbucket for the build status
[01:13:03.735] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:03.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:118:0) after 1m0s
[01:14:03.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:118:0)
[01:14:03.766] Timer tick, asking Buildbucket for the build status
[01:14:03.828] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:03.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:119:0) after 1m0s
[01:15:03.876] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:119:0)
[01:15:03.876] Timer tick, asking Buildbucket for the build status
[01:15:03.958] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:03.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:120:0) after 1m0s
[01:16:04.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:120:0)
[01:16:04.049] Timer tick, asking Buildbucket for the build status
[01:16:04.161] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:04.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:121:0) after 1m0s
[01:17:04.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:121:0)
[01:17:04.192] Timer tick, asking Buildbucket for the build status
[01:17:04.263] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:04.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:122:0) after 1m0s
[01:18:04.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:122:0)
[01:18:04.454] Timer tick, asking Buildbucket for the build status
[01:18:04.503] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:04.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:123:0) after 1m0s
[01:19:04.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:123:0)
[01:19:04.546] Timer tick, asking Buildbucket for the build status
[01:19:04.686] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:04.686] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:124:0) after 1m0s
[01:20:04.709] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:124:0)
[01:20:04.709] Timer tick, asking Buildbucket for the build status
[01:20:04.779] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:04.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:125:0) after 1m0s
[01:21:04.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:125:0)
[01:21:04.860] Timer tick, asking Buildbucket for the build status
[01:21:06.624] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:06.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:126:0) after 1m0s
[01:22:06.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:126:0)
[01:22:06.672] Timer tick, asking Buildbucket for the build status
[01:22:06.853] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:06.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:127:0) after 1m0s
[01:23:06.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:127:0)
[01:23:06.875] Timer tick, asking Buildbucket for the build status
[01:23:06.921] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:06.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:128:0) after 1m0s
[01:24:06.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:128:0)
[01:24:06.942] Timer tick, asking Buildbucket for the build status
[01:24:06.988] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:06.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:129:0) after 1m0s
[01:25:07.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:129:0)
[01:25:07.025] Timer tick, asking Buildbucket for the build status
[01:25:07.083] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:07.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:130:0) after 1m0s
[01:26:07.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:130:0)
[01:26:07.107] Timer tick, asking Buildbucket for the build status
[01:26:07.151] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:07.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:131:0) after 1m0s
[01:27:07.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:131:0)
[01:27:07.188] Timer tick, asking Buildbucket for the build status
[01:27:07.269] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:07.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:132:0) after 1m0s
[01:28:07.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:132:0)
[01:28:07.325] Timer tick, asking Buildbucket for the build status
[01:28:07.413] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:07.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:133:0) after 1m0s
[01:29:07.435] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:133:0)
[01:29:07.435] Timer tick, asking Buildbucket for the build status
[01:29:07.533] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:07.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:134:0) after 1m0s
[01:30:07.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:134:0)
[01:30:07.603] Timer tick, asking Buildbucket for the build status
[01:30:07.745] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:07.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:135:0) after 1m0s
[01:31:07.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:135:0)
[01:31:07.768] Timer tick, asking Buildbucket for the build status
[01:31:07.945] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:07.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:136:0) after 1m0s
[01:32:07.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:136:0)
[01:32:07.970] Timer tick, asking Buildbucket for the build status
[01:32:08.153] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:08.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:137:0) after 1m0s
[01:33:08.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:137:0)
[01:33:08.176] Timer tick, asking Buildbucket for the build status
[01:33:08.233] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:08.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:138:0) after 1m0s
[01:34:08.258] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:138:0)
[01:34:08.258] Timer tick, asking Buildbucket for the build status
[01:34:08.454] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:08.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:139:0) after 1m0s
[01:35:08.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:139:0)
[01:35:08.479] Timer tick, asking Buildbucket for the build status
[01:35:08.596] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:08.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:140:0) after 1m0s
[01:36:08.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:140:0)
[01:36:08.619] Timer tick, asking Buildbucket for the build status
[01:36:08.693] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:08.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:141:0) after 1m0s
[01:37:08.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:141:0)
[01:37:08.763] Timer tick, asking Buildbucket for the build status
[01:37:08.808] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:08.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:142:0) after 1m0s
[01:38:08.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:142:0)
[01:38:08.831] Timer tick, asking Buildbucket for the build status
[01:38:09.246] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:09.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:143:0) after 1m0s
[01:39:09.376] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:143:0)
[01:39:09.376] Timer tick, asking Buildbucket for the build status
[01:39:09.428] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:09.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:144:0) after 1m0s
[01:40:09.461] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:144:0)
[01:40:09.461] Timer tick, asking Buildbucket for the build status
[01:40:09.533] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:09.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:145:0) after 1m0s
[01:41:09.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:145:0)
[01:41:09.554] Timer tick, asking Buildbucket for the build status
[01:41:09.636] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:09.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:146:0) after 1m0s
[01:42:09.670] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:146:0)
[01:42:09.670] Timer tick, asking Buildbucket for the build status
[01:42:09.737] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:09.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:147:0) after 1m0s
[01:43:09.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:147:0)
[01:43:09.829] Timer tick, asking Buildbucket for the build status
[01:43:09.913] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:09.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:148:0) after 1m0s
[01:44:09.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:148:0)
[01:44:09.944] Timer tick, asking Buildbucket for the build status
[01:44:10.110] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:10.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:149:0) after 1m0s
[01:45:10.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:149:0)
[01:45:10.167] Timer tick, asking Buildbucket for the build status
[01:45:10.244] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:10.244] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:150:0) after 1m0s
[01:46:10.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:150:0)
[01:46:10.280] Timer tick, asking Buildbucket for the build status
[01:46:10.421] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:10.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:151:0) after 1m0s
[01:47:10.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:151:0)
[01:47:10.448] Timer tick, asking Buildbucket for the build status
[01:47:10.520] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:10.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:152:0) after 1m0s
[01:48:10.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:152:0)
[01:48:10.542] Timer tick, asking Buildbucket for the build status
[01:48:10.698] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:10.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:153:0) after 1m0s
[01:49:10.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:153:0)
[01:49:10.732] Timer tick, asking Buildbucket for the build status
[01:49:10.843] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:10.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:154:0) after 1m0s
[01:50:10.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:154:0)
[01:50:10.892] Timer tick, asking Buildbucket for the build status
[01:50:10.951] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:10.951] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:155:0) after 1m0s
[01:51:11.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:155:0)
[01:51:11.049] Timer tick, asking Buildbucket for the build status
[01:51:11.101] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:11.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:156:0) after 1m0s
[01:52:11.121] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:156:0)
[01:52:11.121] Timer tick, asking Buildbucket for the build status
[01:52:11.196] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:11.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:157:0) after 1m0s
[01:53:11.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:157:0)
[01:53:11.231] Timer tick, asking Buildbucket for the build status
[01:53:11.298] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:11.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:158:0) after 1m0s
[01:54:11.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:158:0)
[01:54:11.322] Timer tick, asking Buildbucket for the build status
[01:54:11.506] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:11.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:159:0) after 1m0s
[01:55:11.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:159:0)
[01:55:11.540] Timer tick, asking Buildbucket for the build status
[01:55:11.604] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:11.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:160:0) after 1m0s
[01:56:11.630] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:160:0)
[01:56:11.630] Timer tick, asking Buildbucket for the build status
[01:56:11.707] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:11.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:161:0) after 1m0s
[01:57:11.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:161:0)
[01:57:11.743] Timer tick, asking Buildbucket for the build status
[01:57:11.798] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:11.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:162:0) after 1m0s
[01:58:11.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:162:0)
[01:58:11.824] Timer tick, asking Buildbucket for the build status
[01:58:12.003] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:12.003] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:163:0) after 1m0s
[01:59:12.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:163:0)
[01:59:12.047] Timer tick, asking Buildbucket for the build status
[01:59:12.123] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:12.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:164:0) after 1m0s
[02:00:12.181] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:164:0)
[02:00:12.181] Timer tick, asking Buildbucket for the build status
[02:00:12.262] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:12.262] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:165:0) after 1m0s
[02:01:12.289] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:165:0)
[02:01:12.289] Timer tick, asking Buildbucket for the build status
[02:01:12.366] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:12.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:166:0) after 1m0s
[02:02:12.394] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:166:0)
[02:02:12.394] Timer tick, asking Buildbucket for the build status
[02:02:12.614] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:12.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:167:0) after 1m0s
[02:03:12.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:167:0)
[02:03:12.681] Timer tick, asking Buildbucket for the build status
[02:03:12.761] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:12.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:168:0) after 1m0s
[02:04:12.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:168:0)
[02:04:12.790] Timer tick, asking Buildbucket for the build status
[02:04:12.863] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:12.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:169:0) after 1m0s
[02:05:12.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:169:0)
[02:05:12.891] Timer tick, asking Buildbucket for the build status
[02:05:13.037] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:13.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:170:0) after 1m0s
[02:06:13.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:170:0)
[02:06:13.072] Timer tick, asking Buildbucket for the build status
[02:06:13.158] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:13.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:171:0) after 1m0s
[02:07:13.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:171:0)
[02:07:13.182] Timer tick, asking Buildbucket for the build status
[02:07:13.883] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:13.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:172:0) after 1m0s
[02:08:13.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:172:0)
[02:08:13.907] Timer tick, asking Buildbucket for the build status
[02:08:13.983] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:13.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:173:0) after 1m0s
[02:09:14.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:173:0)
[02:09:14.021] Timer tick, asking Buildbucket for the build status
[02:09:14.115] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:14.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:174:0) after 1m0s
[02:10:14.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:174:0)
[02:10:14.148] Timer tick, asking Buildbucket for the build status
[02:10:14.312] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:14.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:175:0) after 1m0s
[02:11:14.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:175:0)
[02:11:14.342] Timer tick, asking Buildbucket for the build status
[02:11:14.382] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:14.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:176:0) after 1m0s
[02:12:14.572] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:176:0)
[02:12:14.572] Timer tick, asking Buildbucket for the build status
[02:12:14.628] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:14.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:177:0) after 1m0s
[02:13:14.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:177:0)
[02:13:14.672] Timer tick, asking Buildbucket for the build status
[02:13:14.745] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:14.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:178:0) after 1m0s
[02:14:14.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:178:0)
[02:14:14.770] Timer tick, asking Buildbucket for the build status
[02:14:14.890] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:14.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:179:0) after 1m0s
[02:15:14.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:179:0)
[02:15:14.914] Timer tick, asking Buildbucket for the build status
[02:15:15.028] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:15.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:180:0) after 1m0s
[02:16:15.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:180:0)
[02:16:15.063] Timer tick, asking Buildbucket for the build status
[02:16:15.130] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:15.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:181:0) after 1m0s
[02:17:15.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:181:0)
[02:17:15.153] Timer tick, asking Buildbucket for the build status
[02:17:15.253] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:15.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:182:0) after 1m0s
[02:18:15.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:182:0)
[02:18:15.294] Timer tick, asking Buildbucket for the build status
[02:18:15.493] Build 8932467837451516976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:15.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9097897899491860512:183:0) after 1m0s
[02:18:38.405] Received PubSub notification, asking Buildbucket for the build status
[02:18:38.497] Build 8932467837451516976: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[02:18:38.497] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1539742717585729",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1539731863505022",
    "experimental": true,
    "failure_reason": "INFRA_FAILURE",
    "id": "8932467837451516976",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"kitchen crashed or got killed\", \"bootstrapperCallStack\": [], \"type\": \"BOOTSTRAPPER_ERROR\"}, \"recipeResult\": null, \"recipeExitCode\": null, \"recipe\": null, \"annotationUrl\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3809-a31bbe1\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-canary-e973df7c-us-central1-c-tkbl\"], \"pool\": [\"luci.nacl.toolchain\"]}}, \"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1539731894479142",
    "status": "COMPLETED",
    "status_changed_ts": "1539742717940042",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9097897899491860512",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_dimension:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_dimension:cores:8",
      "swarming_dimension:cpu:x86-64",
      "swarming_dimension:os:Ubuntu-14.04",
      "swarming_dimension:pool:luci.nacl.toolchain",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
      "swarming_tag:buildbucket_bucket:luci.nacl.toolchain",
      "swarming_tag:buildbucket_build_id:8932467837451516976",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:buildset:commit/git/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/99673869a3cd8731d924bd32fa486feebfdc6c4f",
      "swarming_tag:caches:builder_91fd20d3b70fcaa51af83cb3d5f4f5aac058c8ab0c404858b1894fb462151f3a_v2",
      "swarming_tag:cores:8",
      "swarming_tag:cpu:x86-64",
      "swarming_tag:gitiles_ref:refs/heads/master",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8932467837451516976/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:60",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9097897899491860512",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:82431e273d2f347205266ff4363205d6de25e0ad",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:4097fd42fb1e8310",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1539742717941263",
    "url": "https://ci.chromium.org/p/nacl/builders/luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/8",
    "utcnow_ts": "1539742718469516"
  }
}
[02:18:38.497] Invocation finished in 3h0m56.320583298s with status FAILED