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

• b13f9c675f002215b711619f8e99cbcf90b84125 (6 years ago)

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

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

[15:19:25.556] New invocation is queued and will start shortly
[15:19:26.873] Starting the invocation (attempt 1)
[15:19:26.932] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:19:26.932] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:19:26.932] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9088868293455023504",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"b13f9c675f002215b711619f8e99cbcf90b84125\"}}",
  "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:9088868293455023504",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125",
    "gitiles_ref:refs/heads/master"
  ]
}
[15:19:28.121] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548343167003458",
    "id": "8923438231274724064",
    "lease_expiration_ts": "1548494367924824",
    "lease_key": "1612982994",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"b13f9c675f002215b711619f8e99cbcf90b84125\"}}",
    "project": "nacl",
    "result_details_json": "null",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1548343168016541",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5063",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9088868293455023504",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "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_64/5063",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8923438231274724064",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125",
      "swarming_tag:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "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/8923438231274724064/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9088868293455023504",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:a883ea2343e81ad9b4be8c2424a65260c5b29a9f",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:42994351c7aef810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548343168016558",
    "url": "https://ci.chromium.org/b/8923438231274724064",
    "utcnow_ts": "1548343168110744"
  }
}
[15:19:28.121] Task URL: https://ci.chromium.org/b/8923438231274724064
[15:19:28.121] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:2:0) after 1m0s
[15:20:28.233] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:2:0)
[15:20:28.233] Timer tick, asking Buildbucket for the build status
[15:20:28.317] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:20:28.317] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:3:0) after 1m0s
[15:21:28.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:3:0)
[15:21:28.429] Timer tick, asking Buildbucket for the build status
[15:21:28.467] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:21:28.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:4:0) after 1m0s
[15:22:28.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:4:0)
[15:22:28.495] Timer tick, asking Buildbucket for the build status
[15:22:28.534] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:22:28.534] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:5:0) after 1m0s
[15:23:28.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:5:0)
[15:23:28.579] Timer tick, asking Buildbucket for the build status
[15:23:28.649] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:23:28.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:6:0) after 1m0s
[15:24:28.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:6:0)
[15:24:28.664] Timer tick, asking Buildbucket for the build status
[15:24:28.753] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:24:28.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:7:0) after 1m0s
[15:25:28.771] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:7:0)
[15:25:28.771] Timer tick, asking Buildbucket for the build status
[15:25:28.836] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:25:28.836] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:8:0) after 1m0s
[15:26:28.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:8:0)
[15:26:28.853] Timer tick, asking Buildbucket for the build status
[15:26:29.096] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:26:29.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:9:0) after 1m0s
[15:27:29.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:9:0)
[15:27:29.114] Timer tick, asking Buildbucket for the build status
[15:27:29.173] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:27:29.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:10:0) after 1m0s
[15:28:29.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:10:0)
[15:28:29.188] Timer tick, asking Buildbucket for the build status
[15:28:29.226] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:28:29.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:11:0) after 1m0s
[15:29:29.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:11:0)
[15:29:29.279] Timer tick, asking Buildbucket for the build status
[15:29:29.443] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:29:29.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:12:0) after 1m0s
[15:30:29.464] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:12:0)
[15:30:29.464] Timer tick, asking Buildbucket for the build status
[15:30:29.559] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:30:29.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:13:0) after 1m0s
[15:31:29.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:13:0)
[15:31:29.575] Timer tick, asking Buildbucket for the build status
[15:31:29.666] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:29.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:14:0) after 1m0s
[15:32:29.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:14:0)
[15:32:29.683] Timer tick, asking Buildbucket for the build status
[15:32:29.760] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:29.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:15:0) after 1m0s
[15:33:29.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:15:0)
[15:33:29.782] Timer tick, asking Buildbucket for the build status
[15:33:29.903] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:33:29.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:16:0) after 1m0s
[15:34:29.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:16:0)
[15:34:29.925] Timer tick, asking Buildbucket for the build status
[15:34:30.063] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:34:30.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:17:0) after 1m0s
[15:35:30.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:17:0)
[15:35:30.091] Timer tick, asking Buildbucket for the build status
[15:35:30.125] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:30.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:18:0) after 1m0s
[15:36:30.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:18:0)
[15:36:30.150] Timer tick, asking Buildbucket for the build status
[15:36:30.293] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:30.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:19:0) after 1m0s
[15:37:30.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:19:0)
[15:37:30.308] Timer tick, asking Buildbucket for the build status
[15:37:30.393] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:30.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:20:0) after 1m0s
[15:38:30.459] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:20:0)
[15:38:30.459] Timer tick, asking Buildbucket for the build status
[15:38:30.516] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:30.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:21:0) after 1m0s
[15:39:30.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:21:0)
[15:39:30.532] Timer tick, asking Buildbucket for the build status
[15:39:30.613] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:30.613] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:22:0) after 1m0s
[15:40:30.641] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:22:0)
[15:40:30.641] Timer tick, asking Buildbucket for the build status
[15:40:30.695] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:30.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:23:0) after 1m0s
[15:41:30.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:23:0)
[15:41:30.717] Timer tick, asking Buildbucket for the build status
[15:41:30.894] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:30.894] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:24:0) after 1m0s
[15:42:30.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:24:0)
[15:42:30.914] Timer tick, asking Buildbucket for the build status
[15:42:30.946] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:30.946] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:25:0) after 1m0s
[15:43:30.964] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:25:0)
[15:43:30.964] Timer tick, asking Buildbucket for the build status
[15:43:31.035] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:31.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:26:0) after 1m0s
[15:44:31.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:26:0)
[15:44:31.052] Timer tick, asking Buildbucket for the build status
[15:44:31.152] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:31.152] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:27:0) after 1m0s
[15:45:31.171] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:27:0)
[15:45:31.171] Timer tick, asking Buildbucket for the build status
[15:45:31.298] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:31.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:28:0) after 1m0s
[15:46:31.317] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:28:0)
[15:46:31.317] Timer tick, asking Buildbucket for the build status
[15:46:31.359] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:31.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:29:0) after 1m0s
[15:47:31.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:29:0)
[15:47:31.378] Timer tick, asking Buildbucket for the build status
[15:47:31.432] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:31.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:30:0) after 1m0s
[15:48:31.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:30:0)
[15:48:31.453] Timer tick, asking Buildbucket for the build status
[15:48:31.519] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:31.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:31:0) after 1m0s
[15:49:31.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:31:0)
[15:49:31.538] Timer tick, asking Buildbucket for the build status
[15:49:31.650] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:31.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:32:0) after 1m0s
[15:50:31.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:32:0)
[15:50:31.669] Timer tick, asking Buildbucket for the build status
[15:50:31.716] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:50:31.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:33:0) after 1m0s
[15:51:31.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:33:0)
[15:51:31.749] Timer tick, asking Buildbucket for the build status
[15:51:31.821] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:51:31.821] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:34:0) after 1m0s
[15:52:31.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:34:0)
[15:52:31.838] Timer tick, asking Buildbucket for the build status
[15:52:31.945] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:52:31.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:35:0) after 1m0s
[15:53:31.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:35:0)
[15:53:31.963] Timer tick, asking Buildbucket for the build status
[15:53:32.057] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:53:32.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:36:0) after 1m0s
[15:54:32.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:36:0)
[15:54:32.159] Timer tick, asking Buildbucket for the build status
[15:54:32.193] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:54:32.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:37:0) after 1m0s
[15:55:32.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:37:0)
[15:55:32.212] Timer tick, asking Buildbucket for the build status
[15:55:32.247] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:32.247] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:38:0) after 1m0s
[15:56:32.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:38:0)
[15:56:32.269] Timer tick, asking Buildbucket for the build status
[15:56:32.364] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:32.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:39:0) after 1m0s
[15:57:32.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:39:0)
[15:57:32.383] Timer tick, asking Buildbucket for the build status
[15:57:32.436] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:32.436] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:40:0) after 1m0s
[15:58:32.455] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:40:0)
[15:58:32.455] Timer tick, asking Buildbucket for the build status
[15:58:32.487] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:58:32.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:41:0) after 1m0s
[15:59:32.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:41:0)
[15:59:32.507] Timer tick, asking Buildbucket for the build status
[15:59:32.578] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:59:32.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:42:0) after 1m0s
[16:00:32.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:42:0)
[16:00:32.645] Timer tick, asking Buildbucket for the build status
[16:00:32.702] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:00:32.702] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:43:0) after 1m0s
[16:01:32.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:43:0)
[16:01:32.725] Timer tick, asking Buildbucket for the build status
[16:01:32.787] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:01:32.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:44:0) after 1m0s
[16:02:32.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:44:0)
[16:02:32.806] Timer tick, asking Buildbucket for the build status
[16:02:32.877] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:02:32.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:45:0) after 1m0s
[16:03:32.908] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:45:0)
[16:03:32.908] Timer tick, asking Buildbucket for the build status
[16:03:33.010] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:03:33.010] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:46:0) after 1m0s
[16:04:33.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:46:0)
[16:04:33.030] Timer tick, asking Buildbucket for the build status
[16:04:33.083] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:04:33.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:47:0) after 1m0s
[16:05:33.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:47:0)
[16:05:33.102] Timer tick, asking Buildbucket for the build status
[16:05:33.209] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:33.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:48:0) after 1m0s
[16:06:33.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:48:0)
[16:06:33.228] Timer tick, asking Buildbucket for the build status
[16:06:33.270] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:33.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:49:0) after 1m0s
[16:07:33.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:49:0)
[16:07:33.291] Timer tick, asking Buildbucket for the build status
[16:07:33.375] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:33.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:50:0) after 1m0s
[16:08:33.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:50:0)
[16:08:33.401] Timer tick, asking Buildbucket for the build status
[16:08:33.582] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:33.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:51:0) after 1m0s
[16:09:33.657] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:51:0)
[16:09:33.657] Timer tick, asking Buildbucket for the build status
[16:09:33.710] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:33.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:52:0) after 1m0s
[16:10:33.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:52:0)
[16:10:33.730] Timer tick, asking Buildbucket for the build status
[16:10:33.769] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:33.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:53:0) after 1m0s
[16:11:33.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:53:0)
[16:11:33.789] Timer tick, asking Buildbucket for the build status
[16:11:33.860] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:33.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:54:0) after 1m0s
[16:12:33.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:54:0)
[16:12:33.880] Timer tick, asking Buildbucket for the build status
[16:12:33.919] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:33.919] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:55:0) after 1m0s
[16:13:33.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:55:0)
[16:13:33.943] Timer tick, asking Buildbucket for the build status
[16:13:34.082] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:34.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:56:0) after 1m0s
[16:14:34.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:56:0)
[16:14:34.120] Timer tick, asking Buildbucket for the build status
[16:14:34.182] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:34.182] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:57:0) after 1m0s
[16:15:34.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:57:0)
[16:15:34.203] Timer tick, asking Buildbucket for the build status
[16:15:34.258] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:34.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:58:0) after 1m0s
[16:16:34.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:58:0)
[16:16:34.277] Timer tick, asking Buildbucket for the build status
[16:16:34.315] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:34.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:59:0) after 1m0s
[16:17:34.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:59:0)
[16:17:34.371] Timer tick, asking Buildbucket for the build status
[16:17:34.545] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:34.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:60:0) after 1m0s
[16:18:34.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:60:0)
[16:18:34.568] Timer tick, asking Buildbucket for the build status
[16:18:34.600] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:34.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:61:0) after 1m0s
[16:19:34.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:61:0)
[16:19:34.621] Timer tick, asking Buildbucket for the build status
[16:19:34.683] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:34.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:62:0) after 1m0s
[16:20:34.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:62:0)
[16:20:34.705] Timer tick, asking Buildbucket for the build status
[16:20:34.768] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:34.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:63:0) after 1m0s
[16:21:34.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:63:0)
[16:21:34.787] Timer tick, asking Buildbucket for the build status
[16:21:35.764] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:35.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:64:0) after 1m0s
[16:22:35.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:64:0)
[16:22:35.808] Timer tick, asking Buildbucket for the build status
[16:22:35.842] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:35.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:65:0) after 1m0s
[16:23:35.862] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:65:0)
[16:23:35.862] Timer tick, asking Buildbucket for the build status
[16:23:35.922] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:35.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:66:0) after 1m0s
[16:24:35.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:66:0)
[16:24:35.993] Timer tick, asking Buildbucket for the build status
[16:24:36.037] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:36.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:67:0) after 1m0s
[16:25:36.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:67:0)
[16:25:36.057] Timer tick, asking Buildbucket for the build status
[16:25:36.131] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:36.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:68:0) after 1m0s
[16:26:36.163] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:68:0)
[16:26:36.163] Timer tick, asking Buildbucket for the build status
[16:26:36.243] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:36.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:69:0) after 1m0s
[16:27:36.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:69:0)
[16:27:36.263] Timer tick, asking Buildbucket for the build status
[16:27:36.331] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:36.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:70:0) after 1m0s
[16:28:36.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:70:0)
[16:28:36.385] Timer tick, asking Buildbucket for the build status
[16:28:36.456] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:36.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:71:0) after 1m0s
[16:29:36.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:71:0)
[16:29:36.504] Timer tick, asking Buildbucket for the build status
[16:29:36.563] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:36.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:72:0) after 1m0s
[16:30:36.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:72:0)
[16:30:36.583] Timer tick, asking Buildbucket for the build status
[16:30:36.643] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:36.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:73:0) after 1m0s
[16:31:36.668] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:73:0)
[16:31:36.668] Timer tick, asking Buildbucket for the build status
[16:31:36.726] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:36.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:74:0) after 1m0s
[16:32:36.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:74:0)
[16:32:36.747] Timer tick, asking Buildbucket for the build status
[16:32:36.786] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:36.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:75:0) after 1m0s
[16:33:36.810] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:75:0)
[16:33:36.810] Timer tick, asking Buildbucket for the build status
[16:33:36.884] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:36.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:76:0) after 1m0s
[16:34:36.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:76:0)
[16:34:36.904] Timer tick, asking Buildbucket for the build status
[16:34:36.967] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:36.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:77:0) after 1m0s
[16:35:36.988] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:77:0)
[16:35:36.988] Timer tick, asking Buildbucket for the build status
[16:35:37.053] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:37.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:78:0) after 1m0s
[16:36:37.075] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:78:0)
[16:36:37.075] Timer tick, asking Buildbucket for the build status
[16:36:37.111] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:37.111] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:79:0) after 1m0s
[16:37:37.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:79:0)
[16:37:37.138] Timer tick, asking Buildbucket for the build status
[16:37:37.243] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:37:37.243] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:80:0) after 1m0s
[16:38:37.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:80:0)
[16:38:37.262] Timer tick, asking Buildbucket for the build status
[16:38:37.313] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:37.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:81:0) after 1m0s
[16:39:37.339] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:81:0)
[16:39:37.339] Timer tick, asking Buildbucket for the build status
[16:39:37.375] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:37.375] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:82:0) after 1m0s
[16:40:37.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:82:0)
[16:40:37.396] Timer tick, asking Buildbucket for the build status
[16:40:37.452] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:37.452] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:83:0) after 1m0s
[16:41:37.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:83:0)
[16:41:37.476] Timer tick, asking Buildbucket for the build status
[16:41:37.548] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:37.548] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:84:0) after 1m0s
[16:42:37.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:84:0)
[16:42:37.568] Timer tick, asking Buildbucket for the build status
[16:42:37.972] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:37.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:85:0) after 1m0s
[16:43:37.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:85:0)
[16:43:37.991] Timer tick, asking Buildbucket for the build status
[16:43:38.060] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:43:38.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:86:0) after 1m0s
[16:44:38.079] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:86:0)
[16:44:38.079] Timer tick, asking Buildbucket for the build status
[16:44:38.111] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:38.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:87:0) after 1m0s
[16:45:38.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:87:0)
[16:45:38.150] Timer tick, asking Buildbucket for the build status
[16:45:38.244] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:38.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:88:0) after 1m0s
[16:46:38.266] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:88:0)
[16:46:38.266] Timer tick, asking Buildbucket for the build status
[16:46:38.362] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:38.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:89:0) after 1m0s
[16:47:38.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:89:0)
[16:47:38.381] Timer tick, asking Buildbucket for the build status
[16:47:38.573] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:38.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:90:0) after 1m0s
[16:48:38.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:90:0)
[16:48:38.597] Timer tick, asking Buildbucket for the build status
[16:48:38.629] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:38.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:91:0) after 1m0s
[16:49:38.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:91:0)
[16:49:38.669] Timer tick, asking Buildbucket for the build status
[16:49:38.726] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:38.726] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:92:0) after 1m0s
[16:50:38.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:92:0)
[16:50:38.776] Timer tick, asking Buildbucket for the build status
[16:50:38.869] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:50:38.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:93:0) after 1m0s
[16:51:38.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:93:0)
[16:51:38.890] Timer tick, asking Buildbucket for the build status
[16:51:39.032] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:51:39.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:94:0) after 1m0s
[16:52:39.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:94:0)
[16:52:39.058] Timer tick, asking Buildbucket for the build status
[16:52:39.087] Build 8923438231274724064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:39.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:95:0) after 1m0s
[16:52:55.566] Received PubSub notification, asking Buildbucket for the build status
[16:52:55.645] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:95:0)
[16:53:39.112] Timer tick, asking Buildbucket for the build status
[16:53:39.162] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:97:0) after 1m0s
[16:54:39.187] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:97:0)
[16:54:39.187] Timer tick, asking Buildbucket for the build status
[16:54:39.220] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:39.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:98:0) after 1m0s
[16:55:39.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:98:0)
[16:55:39.240] Timer tick, asking Buildbucket for the build status
[16:55:39.313] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:39.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:99:0) after 1m0s
[16:56:39.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:99:0)
[16:56:39.365] Timer tick, asking Buildbucket for the build status
[16:56:39.425] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:39.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:100:0) after 1m0s
[16:57:39.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:100:0)
[16:57:39.478] Timer tick, asking Buildbucket for the build status
[16:57:39.550] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:39.551] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:101:0) after 1m0s
[16:58:39.571] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:101:0)
[16:58:39.571] Timer tick, asking Buildbucket for the build status
[16:58:39.622] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:39.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:102:0) after 1m0s
[16:59:39.641] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:102:0)
[16:59:39.641] Timer tick, asking Buildbucket for the build status
[16:59:39.694] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:39.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:103:0) after 1m0s
[17:00:39.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:103:0)
[17:00:39.711] Timer tick, asking Buildbucket for the build status
[17:00:39.840] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:39.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:104:0) after 1m0s
[17:01:39.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:104:0)
[17:01:39.913] Timer tick, asking Buildbucket for the build status
[17:01:39.958] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:39.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:105:0) after 1m0s
[17:02:39.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:105:0)
[17:02:39.991] Timer tick, asking Buildbucket for the build status
[17:02:40.506] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:40.506] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:106:0) after 1m0s
[17:03:40.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:106:0)
[17:03:40.529] Timer tick, asking Buildbucket for the build status
[17:03:40.668] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.668] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:107:0) after 1m0s
[17:04:40.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:107:0)
[17:04:40.689] Timer tick, asking Buildbucket for the build status
[17:04:40.720] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:40.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:108:0) after 1m0s
[17:05:40.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:108:0)
[17:05:40.740] Timer tick, asking Buildbucket for the build status
[17:05:40.781] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:40.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:109:0) after 1m0s
[17:06:40.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:109:0)
[17:06:40.802] Timer tick, asking Buildbucket for the build status
[17:06:40.870] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:40.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:110:0) after 1m0s
[17:07:40.889] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:110:0)
[17:07:40.889] Timer tick, asking Buildbucket for the build status
[17:07:40.952] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:40.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:111:0) after 1m0s
[17:08:41.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:111:0)
[17:08:41.003] Timer tick, asking Buildbucket for the build status
[17:08:41.083] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:41.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:112:0) after 1m0s
[17:09:41.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:112:0)
[17:09:41.112] Timer tick, asking Buildbucket for the build status
[17:09:41.252] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:41.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:113:0) after 1m0s
[17:10:41.272] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:113:0)
[17:10:41.272] Timer tick, asking Buildbucket for the build status
[17:10:41.306] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:41.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:114:0) after 1m0s
[17:11:41.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:114:0)
[17:11:41.361] Timer tick, asking Buildbucket for the build status
[17:11:41.396] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:41.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:115:0) after 1m0s
[17:12:41.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:115:0)
[17:12:41.504] Timer tick, asking Buildbucket for the build status
[17:12:41.559] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:41.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:116:0) after 1m0s
[17:13:41.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:116:0)
[17:13:41.580] Timer tick, asking Buildbucket for the build status
[17:13:41.706] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:41.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:117:0) after 1m0s
[17:14:41.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:117:0)
[17:14:41.726] Timer tick, asking Buildbucket for the build status
[17:14:41.797] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:41.797] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:118:0) after 1m0s
[17:15:41.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:118:0)
[17:15:41.818] Timer tick, asking Buildbucket for the build status
[17:15:41.870] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:41.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:119:0) after 1m0s
[17:16:41.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:119:0)
[17:16:41.917] Timer tick, asking Buildbucket for the build status
[17:16:42.020] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:42.020] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:120:0) after 1m0s
[17:17:42.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:120:0)
[17:17:42.043] Timer tick, asking Buildbucket for the build status
[17:17:42.110] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:42.110] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:121:0) after 1m0s
[17:18:42.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:121:0)
[17:18:42.130] Timer tick, asking Buildbucket for the build status
[17:18:42.242] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:42.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:122:0) after 1m0s
[17:19:42.261] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:122:0)
[17:19:42.261] Timer tick, asking Buildbucket for the build status
[17:19:42.315] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:42.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:123:0) after 1m0s
[17:20:42.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:123:0)
[17:20:42.334] Timer tick, asking Buildbucket for the build status
[17:20:42.444] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:42.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:124:0) after 1m0s
[17:21:42.475] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:124:0)
[17:21:42.475] Timer tick, asking Buildbucket for the build status
[17:21:42.541] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:42.541] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:125:0) after 1m0s
[17:22:42.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:125:0)
[17:22:42.565] Timer tick, asking Buildbucket for the build status
[17:22:42.617] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:42.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:126:0) after 1m0s
[17:23:42.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:126:0)
[17:23:42.637] Timer tick, asking Buildbucket for the build status
[17:23:42.684] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:42.684] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:127:0) after 1m0s
[17:24:42.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:127:0)
[17:24:42.708] Timer tick, asking Buildbucket for the build status
[17:24:42.867] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:42.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:128:0) after 1m0s
[17:25:42.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:128:0)
[17:25:42.890] Timer tick, asking Buildbucket for the build status
[17:25:43.082] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:43.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:129:0) after 1m0s
[17:26:43.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:129:0)
[17:26:43.159] Timer tick, asking Buildbucket for the build status
[17:26:43.215] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:43.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:130:0) after 1m0s
[17:27:43.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:130:0)
[17:27:43.240] Timer tick, asking Buildbucket for the build status
[17:27:43.348] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:43.349] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:131:0) after 1m0s
[17:28:43.371] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:131:0)
[17:28:43.371] Timer tick, asking Buildbucket for the build status
[17:28:43.408] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:43.408] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:132:0) after 1m0s
[17:29:43.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:132:0)
[17:29:43.431] Timer tick, asking Buildbucket for the build status
[17:29:43.573] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:43.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:133:0) after 1m0s
[17:30:43.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:133:0)
[17:30:43.615] Timer tick, asking Buildbucket for the build status
[17:30:43.682] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:43.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:134:0) after 1m0s
[17:31:43.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:134:0)
[17:31:43.750] Timer tick, asking Buildbucket for the build status
[17:31:43.788] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:43.788] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:135:0) after 1m0s
[17:32:43.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:135:0)
[17:32:43.809] Timer tick, asking Buildbucket for the build status
[17:32:43.940] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:43.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:136:0) after 1m0s
[17:33:43.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:136:0)
[17:33:43.965] Timer tick, asking Buildbucket for the build status
[17:33:44.080] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:44.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:137:0) after 1m0s
[17:34:44.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:137:0)
[17:34:44.099] Timer tick, asking Buildbucket for the build status
[17:34:44.201] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:44.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:138:0) after 1m0s
[17:35:44.246] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:138:0)
[17:35:44.246] Timer tick, asking Buildbucket for the build status
[17:35:44.379] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:44.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:139:0) after 1m0s
[17:36:44.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:139:0)
[17:36:44.406] Timer tick, asking Buildbucket for the build status
[17:36:44.458] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:44.458] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:140:0) after 1m0s
[17:37:44.557] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:140:0)
[17:37:44.557] Timer tick, asking Buildbucket for the build status
[17:37:44.661] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:44.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:141:0) after 1m0s
[17:38:44.680] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:141:0)
[17:38:44.680] Timer tick, asking Buildbucket for the build status
[17:38:44.715] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:44.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:142:0) after 1m0s
[17:39:44.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:142:0)
[17:39:44.751] Timer tick, asking Buildbucket for the build status
[17:39:44.804] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:44.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:143:0) after 1m0s
[17:40:44.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:143:0)
[17:40:44.830] Timer tick, asking Buildbucket for the build status
[17:40:44.870] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:44.870] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:144:0) after 1m0s
[17:41:44.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:144:0)
[17:41:44.891] Timer tick, asking Buildbucket for the build status
[17:41:44.952] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:44.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:145:0) after 1m0s
[17:42:44.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:145:0)
[17:42:44.973] Timer tick, asking Buildbucket for the build status
[17:42:45.154] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:45.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:146:0) after 1m0s
[17:43:45.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:146:0)
[17:43:45.178] Timer tick, asking Buildbucket for the build status
[17:43:45.219] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:45.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:147:0) after 1m0s
[17:44:45.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:147:0)
[17:44:45.241] Timer tick, asking Buildbucket for the build status
[17:44:45.306] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:45.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:148:0) after 1m0s
[17:45:45.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:148:0)
[17:45:45.329] Timer tick, asking Buildbucket for the build status
[17:45:45.386] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:45.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:149:0) after 1m0s
[17:46:45.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:149:0)
[17:46:45.479] Timer tick, asking Buildbucket for the build status
[17:46:45.514] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:45.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:150:0) after 1m0s
[17:47:45.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:150:0)
[17:47:45.541] Timer tick, asking Buildbucket for the build status
[17:47:45.592] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:45.592] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:151:0) after 1m0s
[17:48:45.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:151:0)
[17:48:45.617] Timer tick, asking Buildbucket for the build status
[17:48:45.647] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:45.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:152:0) after 1m0s
[17:49:45.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:152:0)
[17:49:45.669] Timer tick, asking Buildbucket for the build status
[17:49:45.740] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:45.740] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:153:0) after 1m0s
[17:50:45.761] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:153:0)
[17:50:45.761] Timer tick, asking Buildbucket for the build status
[17:50:45.800] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:45.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:154:0) after 1m0s
[17:51:45.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:154:0)
[17:51:45.824] Timer tick, asking Buildbucket for the build status
[17:51:45.873] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:45.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:155:0) after 1m0s
[17:52:45.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:155:0)
[17:52:45.895] Timer tick, asking Buildbucket for the build status
[17:52:45.998] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:45.998] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:156:0) after 1m0s
[17:53:46.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:156:0)
[17:53:46.023] Timer tick, asking Buildbucket for the build status
[17:53:46.154] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:157:0) after 1m0s
[17:54:46.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:157:0)
[17:54:46.210] Timer tick, asking Buildbucket for the build status
[17:54:46.358] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:46.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:158:0) after 1m0s
[17:55:46.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:158:0)
[17:55:46.382] Timer tick, asking Buildbucket for the build status
[17:55:46.419] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:46.419] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:159:0) after 1m0s
[17:56:46.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:159:0)
[17:56:46.441] Timer tick, asking Buildbucket for the build status
[17:56:46.525] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:46.525] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:160:0) after 1m0s
[17:57:46.545] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:160:0)
[17:57:46.545] Timer tick, asking Buildbucket for the build status
[17:57:46.601] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:46.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:161:0) after 1m0s
[17:58:46.628] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:161:0)
[17:58:46.628] Timer tick, asking Buildbucket for the build status
[17:58:46.665] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:46.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:162:0) after 1m0s
[17:59:46.685] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:162:0)
[17:59:46.685] Timer tick, asking Buildbucket for the build status
[17:59:46.716] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:46.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:163:0) after 1m0s
[18:00:46.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:163:0)
[18:00:46.742] Timer tick, asking Buildbucket for the build status
[18:00:46.794] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:46.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:164:0) after 1m0s
[18:01:46.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:164:0)
[18:01:46.820] Timer tick, asking Buildbucket for the build status
[18:01:46.869] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:46.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:165:0) after 1m0s
[18:02:46.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:165:0)
[18:02:46.930] Timer tick, asking Buildbucket for the build status
[18:02:46.992] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:46.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:166:0) after 1m0s
[18:03:55.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:166:0)
[18:03:55.763] Timer tick, asking Buildbucket for the build status
[18:03:55.823] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:55.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:167:0) after 1m0s
[18:04:55.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:167:0)
[18:04:55.997] Timer tick, asking Buildbucket for the build status
[18:04:56.066] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:56.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:168:0) after 1m0s
[18:05:56.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:168:0)
[18:05:56.089] Timer tick, asking Buildbucket for the build status
[18:05:56.197] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:56.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:169:0) after 1m0s
[18:06:56.221] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:169:0)
[18:06:56.221] Timer tick, asking Buildbucket for the build status
[18:06:56.266] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:56.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:170:0) after 1m0s
[18:07:56.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:170:0)
[18:07:56.301] Timer tick, asking Buildbucket for the build status
[18:07:56.389] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:56.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:171:0) after 1m0s
[18:08:56.413] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:171:0)
[18:08:56.413] Timer tick, asking Buildbucket for the build status
[18:08:56.448] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:56.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:172:0) after 1m0s
[18:09:56.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:172:0)
[18:09:56.473] Timer tick, asking Buildbucket for the build status
[18:09:56.526] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:56.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:173:0) after 1m0s
[18:10:56.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:173:0)
[18:10:56.570] Timer tick, asking Buildbucket for the build status
[18:10:56.662] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:56.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:174:0) after 1m0s
[18:11:56.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:174:0)
[18:11:56.691] Timer tick, asking Buildbucket for the build status
[18:11:56.744] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:56.744] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:175:0) after 1m0s
[18:12:56.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:175:0)
[18:12:56.784] Timer tick, asking Buildbucket for the build status
[18:12:56.813] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:56.813] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:176:0) after 1m0s
[18:13:56.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:176:0)
[18:13:56.858] Timer tick, asking Buildbucket for the build status
[18:13:56.891] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:56.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:177:0) after 1m0s
[18:14:57.022] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:177:0)
[18:14:57.022] Timer tick, asking Buildbucket for the build status
[18:14:57.146] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:57.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:178:0) after 1m0s
[18:15:57.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:178:0)
[18:15:57.196] Timer tick, asking Buildbucket for the build status
[18:15:57.277] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:57.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:179:0) after 1m0s
[18:16:57.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:179:0)
[18:16:57.334] Timer tick, asking Buildbucket for the build status
[18:16:57.454] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:57.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:180:0) after 1m0s
[18:17:57.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:180:0)
[18:17:57.477] Timer tick, asking Buildbucket for the build status
[18:17:57.519] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:57.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:181:0) after 1m0s
[18:18:57.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:181:0)
[18:18:57.544] Timer tick, asking Buildbucket for the build status
[18:18:57.599] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:57.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:182:0) after 1m0s
[18:19:57.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:182:0)
[18:19:57.584] Timer tick, asking Buildbucket for the build status
[18:19:57.615] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:57.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:183:0) after 1m0s
[18:20:57.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:183:0)
[18:20:57.639] Timer tick, asking Buildbucket for the build status
[18:20:57.708] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:57.708] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:184:0) after 1m0s
[18:21:57.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:184:0)
[18:21:57.734] Timer tick, asking Buildbucket for the build status
[18:21:57.784] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:57.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:185:0) after 1m0s
[18:22:57.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:185:0)
[18:22:57.811] Timer tick, asking Buildbucket for the build status
[18:22:57.844] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:57.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:186:0) after 1m0s
[18:23:57.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:186:0)
[18:23:57.871] Timer tick, asking Buildbucket for the build status
[18:23:57.923] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:57.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:187:0) after 1m0s
[18:24:57.949] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:187:0)
[18:24:57.949] Timer tick, asking Buildbucket for the build status
[18:24:58.054] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:58.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:188:0) after 1m0s
[18:25:58.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:188:0)
[18:25:58.083] Timer tick, asking Buildbucket for the build status
[18:25:58.137] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:58.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:189:0) after 1m0s
[18:26:58.159] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:189:0)
[18:26:58.159] Timer tick, asking Buildbucket for the build status
[18:26:58.195] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:58.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:190:0) after 1m0s
[18:27:58.228] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:190:0)
[18:27:58.229] Timer tick, asking Buildbucket for the build status
[18:27:58.281] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:58.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:191:0) after 1m0s
[18:28:58.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:191:0)
[18:28:58.307] Timer tick, asking Buildbucket for the build status
[18:28:58.348] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:58.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:192:0) after 1m0s
[18:29:58.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:192:0)
[18:29:58.378] Timer tick, asking Buildbucket for the build status
[18:29:58.431] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:58.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:193:0) after 1m0s
[18:30:58.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:193:0)
[18:30:58.456] Timer tick, asking Buildbucket for the build status
[18:30:58.517] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:58.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:194:0) after 1m0s
[18:31:58.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:194:0)
[18:31:58.552] Timer tick, asking Buildbucket for the build status
[18:31:58.594] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:58.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:195:0) after 1m0s
[18:32:58.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:195:0)
[18:32:58.617] Timer tick, asking Buildbucket for the build status
[18:32:58.672] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:58.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:196:0) after 1m0s
[18:33:58.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:196:0)
[18:33:58.699] Timer tick, asking Buildbucket for the build status
[18:33:58.777] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:58.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:197:0) after 1m0s
[18:34:58.802] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:197:0)
[18:34:58.802] Timer tick, asking Buildbucket for the build status
[18:34:58.842] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:58.842] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:198:0) after 1m0s
[18:35:58.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:198:0)
[18:35:58.866] Timer tick, asking Buildbucket for the build status
[18:35:58.908] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:58.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:199:0) after 1m0s
[18:36:58.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:199:0)
[18:36:58.932] Timer tick, asking Buildbucket for the build status
[18:36:59.073] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:59.073] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:200:0) after 1m0s
[18:37:59.096] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:200:0)
[18:37:59.096] Timer tick, asking Buildbucket for the build status
[18:37:59.174] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:59.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:201:0) after 1m0s
[18:38:59.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:201:0)
[18:38:59.202] Timer tick, asking Buildbucket for the build status
[18:38:59.363] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:59.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:202:0) after 1m0s
[18:39:59.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:202:0)
[18:39:59.390] Timer tick, asking Buildbucket for the build status
[18:39:59.578] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:59.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:203:0) after 1m0s
[18:40:59.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:203:0)
[18:40:59.604] Timer tick, asking Buildbucket for the build status
[18:40:59.643] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:59.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:204:0) after 1m0s
[18:41:59.677] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:204:0)
[18:41:59.677] Timer tick, asking Buildbucket for the build status
[18:41:59.734] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:59.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:205:0) after 1m0s
[18:42:59.759] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:205:0)
[18:42:59.759] Timer tick, asking Buildbucket for the build status
[18:42:59.833] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:59.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:206:0) after 1m0s
[18:43:59.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:206:0)
[18:43:59.866] Timer tick, asking Buildbucket for the build status
[18:44:00.071] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:00.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:207:0) after 1m0s
[18:45:00.095] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:207:0)
[18:45:00.095] Timer tick, asking Buildbucket for the build status
[18:45:00.137] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:00.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:208:0) after 1m0s
[18:46:00.162] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:208:0)
[18:46:00.162] Timer tick, asking Buildbucket for the build status
[18:46:00.284] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:00.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:209:0) after 1m0s
[18:47:00.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:209:0)
[18:47:00.309] Timer tick, asking Buildbucket for the build status
[18:47:00.374] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:00.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:210:0) after 1m0s
[18:48:00.401] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:210:0)
[18:48:00.401] Timer tick, asking Buildbucket for the build status
[18:48:00.463] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:00.463] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:211:0) after 1m0s
[18:49:00.492] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:211:0)
[18:49:00.492] Timer tick, asking Buildbucket for the build status
[18:49:00.545] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:00.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:212:0) after 1m0s
[18:50:00.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:212:0)
[18:50:00.576] Timer tick, asking Buildbucket for the build status
[18:50:00.673] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:00.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:213:0) after 1m0s
[18:51:00.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:213:0)
[18:51:00.698] Timer tick, asking Buildbucket for the build status
[18:51:00.758] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:00.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:214:0) after 1m0s
[18:52:00.782] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:214:0)
[18:52:00.782] Timer tick, asking Buildbucket for the build status
[18:52:00.902] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:00.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:215:0) after 1m0s
[18:53:00.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:215:0)
[18:53:00.935] Timer tick, asking Buildbucket for the build status
[18:53:01.056] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:01.056] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:216:0) after 1m0s
[18:54:01.083] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:216:0)
[18:54:01.083] Timer tick, asking Buildbucket for the build status
[18:54:01.258] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:01.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:217:0) after 1m0s
[18:55:01.283] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:217:0)
[18:55:01.283] Timer tick, asking Buildbucket for the build status
[18:55:01.341] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:01.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:218:0) after 1m0s
[18:56:01.407] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:218:0)
[18:56:01.407] Timer tick, asking Buildbucket for the build status
[18:56:01.466] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:01.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:219:0) after 1m0s
[18:57:01.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:219:0)
[18:57:01.496] Timer tick, asking Buildbucket for the build status
[18:57:01.550] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:01.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:220:0) after 1m0s
[18:58:01.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:220:0)
[18:58:01.585] Timer tick, asking Buildbucket for the build status
[18:58:01.646] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:01.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:221:0) after 1m0s
[18:59:01.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:221:0)
[18:59:01.672] Timer tick, asking Buildbucket for the build status
[18:59:01.735] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:01.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:222:0) after 1m0s
[19:00:01.778] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:222:0)
[19:00:01.778] Timer tick, asking Buildbucket for the build status
[19:00:01.887] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:01.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:223:0) after 1m0s
[19:01:01.932] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:223:0)
[19:01:01.932] Timer tick, asking Buildbucket for the build status
[19:01:02.350] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:02.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:224:0) after 1m0s
[19:02:02.462] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:224:0)
[19:02:02.462] Timer tick, asking Buildbucket for the build status
[19:02:02.550] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:02.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:225:0) after 1m0s
[19:03:02.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:225:0)
[19:03:02.608] Timer tick, asking Buildbucket for the build status
[19:03:02.791] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:02.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:226:0) after 1m0s
[19:04:02.822] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:226:0)
[19:04:02.822] Timer tick, asking Buildbucket for the build status
[19:04:02.881] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:02.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:227:0) after 1m0s
[19:05:02.914] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:227:0)
[19:05:02.914] Timer tick, asking Buildbucket for the build status
[19:05:03.037] Build 8923438231274724064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:03.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9088868293455023504:228:0) after 1m0s
[19:05:24.230] Received PubSub notification, asking Buildbucket for the build status
[19:05:24.269] Build 8923438231274724064: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:05:24.269] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "AUTO",
    "completed_ts": "1548356723291495",
    "created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
    "created_ts": "1548343167003458",
    "failure_reason": "BUILD_FAILURE",
    "id": "8923438231274724064",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"b13f9c675f002215b711619f8e99cbcf90b84125\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"ui\": {\"info\": \"Failure annotated steps\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O0b\\nFailure llvm-test-suite x86-64 --opt O3f --opt O2b_sz\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-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\": [\"4026-11b7a84\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-1cdl\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 5063, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"got_revision\": \"b13f9c675f002215b711619f8e99cbcf90b84125\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1548343167003458, \"tags\": [\"builder:linux-pnacl-x86_64-tests-x86_64\", \"buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125\", \"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9088868293455023504\", \"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.nacl.toolchain\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"nacl\", \"id\": \"8923438231274724064\"}}, \"slavetype\": \"BuilderTester\", \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"b13f9c675f002215b711619f8e99cbcf90b84125\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-1cdl\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1548348774540668",
    "status": "COMPLETED",
    "status_changed_ts": "1548356723908762",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5063",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9088868293455023504",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_dimension:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "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_64/5063",
      "swarming_tag:buildbucket_bucket:nacl/toolchain",
      "swarming_tag:buildbucket_build_id:8923438231274724064",
      "swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
      "swarming_tag:buildbucket_template_canary:0",
      "swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
      "swarming_tag:builder:linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:buildset:commit/git/b13f9c675f002215b711619f8e99cbcf90b84125",
      "swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/b13f9c675f002215b711619f8e99cbcf90b84125",
      "swarming_tag:caches:builder_bf14184df246ade0cf783e61825966ff5c624694bcccef3edd9032289b04d52b_v2",
      "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/8923438231274724064/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Ubuntu-14.04",
      "swarming_tag:pool:luci.nacl.toolchain",
      "swarming_tag:priority:30",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_tag:scheduler_invocation_id:9088868293455023504",
      "swarming_tag:scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_tag:service_account:nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
      "swarming_tag:swarming.pool.template:skip",
      "swarming_tag:swarming.pool.version:a883ea2343e81ad9b4be8c2424a65260c5b29a9f",
      "swarming_tag:user:None",
      "swarming_tag:user_agent:luci-scheduler",
      "swarming_tag:vpython:native-python-wrapper",
      "swarming_task_id:42994351c7aef810",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1548356723910120",
    "url": "https://ci.chromium.org/b/8923438231274724064",
    "utcnow_ts": "1548356724259610"
  }
}
[19:05:24.269] Invocation finished in 3h45m58.728821748s with status FAILED