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

• 82ac8c0a6f0d3ffc843c693dd5149a356b866ae5 (4 years ago)

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

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

[02:04:02.141] New invocation is queued and will start shortly
[02:04:03.598] Starting the invocation (attempt 1)
[02:04:03.635] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:04:03.635] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:04:03.635] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9021061207248333552",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\",\"title\":\"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}}",
  "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:9021061207248333552",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
    "gitiles_ref:refs/heads/master"
  ]
}
[02:04:04.660] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:nacl",
    "created_ts": "1613009043756066",
    "id": "8855631144893018432",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"title\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1613009044491760",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5127",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021061207248333552",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8855631144893018432/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1613009044491929",
    "url": "https://ci.chromium.org/b/8855631144893018432",
    "utcnow_ts": "1613009044644794"
  }
}
[02:04:04.660] Task URL: https://ci.chromium.org/b/8855631144893018432
[02:04:04.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:2:0) after 1m0s
[02:05:05.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:2:0)
[02:05:05.004] Timer tick, asking Buildbucket for the build status
[02:05:05.104] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:05.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:3:0) after 1m0s
[02:06:05.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:3:0)
[02:06:05.308] Timer tick, asking Buildbucket for the build status
[02:06:05.513] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:05.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:4:0) after 1m0s
[02:07:05.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:4:0)
[02:07:05.530] Timer tick, asking Buildbucket for the build status
[02:07:05.664] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:05.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:5:0) after 1m0s
[02:08:05.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:5:0)
[02:08:05.704] Timer tick, asking Buildbucket for the build status
[02:08:05.796] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:05.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:6:0) after 1m0s
[02:09:05.865] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:6:0)
[02:09:05.865] Timer tick, asking Buildbucket for the build status
[02:09:05.961] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:05.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:7:0) after 1m0s
[02:10:06.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:7:0)
[02:10:06.027] Timer tick, asking Buildbucket for the build status
[02:10:06.300] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:06.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:8:0) after 1m0s
[02:11:06.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:8:0)
[02:11:06.446] Timer tick, asking Buildbucket for the build status
[02:11:06.577] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:06.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:9:0) after 1m0s
[02:12:06.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:9:0)
[02:12:06.733] Timer tick, asking Buildbucket for the build status
[02:12:06.835] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:06.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:10:0) after 1m0s
[02:13:06.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:10:0)
[02:13:06.898] Timer tick, asking Buildbucket for the build status
[02:13:07.201] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:07.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:11:0) after 1m0s
[02:14:07.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:11:0)
[02:14:07.254] Timer tick, asking Buildbucket for the build status
[02:14:07.367] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:07.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:12:0) after 1m0s
[02:15:07.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:12:0)
[02:15:07.386] Timer tick, asking Buildbucket for the build status
[02:15:07.516] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:07.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:13:0) after 1m0s
[02:16:07.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:13:0)
[02:16:07.534] Timer tick, asking Buildbucket for the build status
[02:16:08.823] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:08.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:14:0) after 1m0s
[02:17:08.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:14:0)
[02:17:08.826] Timer tick, asking Buildbucket for the build status
[02:17:08.911] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:08.911] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:15:0) after 1m0s
[02:18:08.881] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:15:0)
[02:18:08.881] Timer tick, asking Buildbucket for the build status
[02:18:09.102] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:09.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:16:0) after 1m0s
[02:19:09.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:16:0)
[02:19:09.183] Timer tick, asking Buildbucket for the build status
[02:19:09.359] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:09.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:17:0) after 1m0s
[02:20:09.429] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:17:0)
[02:20:09.429] Timer tick, asking Buildbucket for the build status
[02:20:09.730] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:09.730] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:18:0) after 1m0s
[02:21:09.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:18:0)
[02:21:09.774] Timer tick, asking Buildbucket for the build status
[02:21:10.012] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:10.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:19:0) after 1m0s
[02:22:10.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:19:0)
[02:22:10.060] Timer tick, asking Buildbucket for the build status
[02:22:10.168] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:10.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:20:0) after 1m0s
[02:23:10.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:20:0)
[02:23:10.268] Timer tick, asking Buildbucket for the build status
[02:23:10.366] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:10.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:21:0) after 1m0s
[02:24:10.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:21:0)
[02:24:10.454] Timer tick, asking Buildbucket for the build status
[02:24:10.743] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:10.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:22:0) after 1m0s
[02:25:10.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:22:0)
[02:25:10.791] Timer tick, asking Buildbucket for the build status
[02:25:10.932] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:10.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:23:0) after 1m0s
[02:26:10.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:23:0)
[02:26:10.979] Timer tick, asking Buildbucket for the build status
[02:26:11.116] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:11.116] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:24:0) after 1m0s
[02:27:11.128] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:24:0)
[02:27:11.128] Timer tick, asking Buildbucket for the build status
[02:27:11.235] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:11.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:25:0) after 1m0s
[02:28:11.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:25:0)
[02:28:11.407] Timer tick, asking Buildbucket for the build status
[02:28:11.691] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:11.691] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:26:0) after 1m0s
[02:29:11.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:26:0)
[02:29:11.812] Timer tick, asking Buildbucket for the build status
[02:29:11.923] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:11.923] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:27:0) after 1m0s
[02:30:12.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:27:0)
[02:30:12.084] Timer tick, asking Buildbucket for the build status
[02:30:12.315] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:12.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:28:0) after 1m0s
[02:31:12.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:28:0)
[02:31:12.397] Timer tick, asking Buildbucket for the build status
[02:31:12.478] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:12.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:29:0) after 1m0s
[02:32:12.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:29:0)
[02:32:12.590] Timer tick, asking Buildbucket for the build status
[02:32:12.748] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:12.748] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:30:0) after 1m0s
[02:33:12.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:30:0)
[02:33:12.751] Timer tick, asking Buildbucket for the build status
[02:33:12.939] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:12.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:31:0) after 1m0s
[02:34:12.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:31:0)
[02:34:12.997] Timer tick, asking Buildbucket for the build status
[02:34:13.588] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:13.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:32:0) after 1m0s
[02:35:13.694] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:32:0)
[02:35:13.694] Timer tick, asking Buildbucket for the build status
[02:35:13.900] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:13.900] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:33:0) after 1m0s
[02:36:13.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:33:0)
[02:36:13.906] Timer tick, asking Buildbucket for the build status
[02:36:14.319] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:14.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:34:0) after 1m0s
[02:37:14.343] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:34:0)
[02:37:14.343] Timer tick, asking Buildbucket for the build status
[02:37:14.513] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:14.513] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:35:0) after 1m0s
[02:38:14.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:35:0)
[02:38:14.506] Timer tick, asking Buildbucket for the build status
[02:38:14.719] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:14.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:36:0) after 1m0s
[02:39:14.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:36:0)
[02:39:14.698] Timer tick, asking Buildbucket for the build status
[02:39:14.947] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:14.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:37:0) after 1m0s
[02:40:14.993] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:37:0)
[02:40:14.993] Timer tick, asking Buildbucket for the build status
[02:40:15.091] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:15.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:38:0) after 1m0s
[02:41:15.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:38:0)
[02:41:15.062] Timer tick, asking Buildbucket for the build status
[02:41:15.170] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:15.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:39:0) after 1m0s
[02:42:15.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:39:0)
[02:42:15.219] Timer tick, asking Buildbucket for the build status
[02:42:15.296] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:15.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:40:0) after 1m0s
[02:43:15.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:40:0)
[02:43:15.332] Timer tick, asking Buildbucket for the build status
[02:43:15.439] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:15.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:41:0) after 1m0s
[02:44:15.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:41:0)
[02:44:15.454] Timer tick, asking Buildbucket for the build status
[02:44:15.624] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:15.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:42:0) after 1m0s
[02:45:15.598] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:42:0)
[02:45:15.598] Timer tick, asking Buildbucket for the build status
[02:45:15.700] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:15.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:43:0) after 1m0s
[02:46:15.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:43:0)
[02:46:15.748] Timer tick, asking Buildbucket for the build status
[02:46:15.879] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:15.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:44:0) after 1m0s
[02:47:15.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:44:0)
[02:47:15.903] Timer tick, asking Buildbucket for the build status
[02:47:15.985] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:15.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:45:0) after 1m0s
[02:48:16.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:45:0)
[02:48:16.015] Timer tick, asking Buildbucket for the build status
[02:48:16.129] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:16.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:46:0) after 1m0s
[02:49:16.194] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:46:0)
[02:49:16.194] Timer tick, asking Buildbucket for the build status
[02:49:16.319] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:16.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:47:0) after 1m0s
[02:50:16.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:47:0)
[02:50:16.332] Timer tick, asking Buildbucket for the build status
[02:50:16.414] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:16.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:48:0) after 1m0s
[02:51:16.532] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:48:0)
[02:51:16.532] Timer tick, asking Buildbucket for the build status
[02:51:16.699] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:16.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:49:0) after 1m0s
[02:52:16.775] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:49:0)
[02:52:16.775] Timer tick, asking Buildbucket for the build status
[02:52:16.914] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:16.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:50:0) after 1m0s
[02:53:17.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:50:0)
[02:53:17.045] Timer tick, asking Buildbucket for the build status
[02:53:17.131] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:17.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:51:0) after 1m0s
[02:54:17.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:51:0)
[02:54:17.320] Timer tick, asking Buildbucket for the build status
[02:54:17.633] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:17.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:52:0) after 1m0s
[02:55:17.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:52:0)
[02:55:17.717] Timer tick, asking Buildbucket for the build status
[02:55:17.794] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:17.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:53:0) after 1m0s
[02:56:17.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:53:0)
[02:56:17.841] Timer tick, asking Buildbucket for the build status
[02:56:18.059] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:18.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:54:0) after 1m0s
[02:57:18.123] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:54:0)
[02:57:18.123] Timer tick, asking Buildbucket for the build status
[02:57:18.319] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:18.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:55:0) after 1m0s
[02:58:18.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:55:0)
[02:58:18.360] Timer tick, asking Buildbucket for the build status
[02:58:18.498] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:18.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:56:0) after 1m0s
[02:59:18.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:56:0)
[02:59:18.524] Timer tick, asking Buildbucket for the build status
[02:59:18.699] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:18.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:57:0) after 1m0s
[03:00:18.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:57:0)
[03:00:18.718] Timer tick, asking Buildbucket for the build status
[03:00:18.833] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:18.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:58:0) after 1m0s
[03:01:18.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:58:0)
[03:01:18.915] Timer tick, asking Buildbucket for the build status
[03:01:19.366] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:19.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:59:0) after 1m0s
[03:02:19.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:59:0)
[03:02:19.456] Timer tick, asking Buildbucket for the build status
[03:02:19.556] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:19.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:60:0) after 1m0s
[03:03:19.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:60:0)
[03:03:19.575] Timer tick, asking Buildbucket for the build status
[03:03:19.833] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:19.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:61:0) after 1m0s
[03:04:19.880] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:61:0)
[03:04:19.880] Timer tick, asking Buildbucket for the build status
[03:04:20.215] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:20.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:62:0) after 1m0s
[03:05:20.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:62:0)
[03:05:20.262] Timer tick, asking Buildbucket for the build status
[03:05:20.362] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:20.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:63:0) after 1m0s
[03:06:20.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:63:0)
[03:06:20.382] Timer tick, asking Buildbucket for the build status
[03:06:20.509] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:20.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:64:0) after 1m0s
[03:07:20.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:64:0)
[03:07:20.542] Timer tick, asking Buildbucket for the build status
[03:07:20.645] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:20.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:65:0) after 1m0s
[03:08:20.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:65:0)
[03:08:20.663] Timer tick, asking Buildbucket for the build status
[03:08:20.860] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:20.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:66:0) after 1m0s
[03:09:20.847] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:66:0)
[03:09:20.847] Timer tick, asking Buildbucket for the build status
[03:09:21.282] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:21.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:67:0) after 1m0s
[03:10:21.309] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:67:0)
[03:10:21.309] Timer tick, asking Buildbucket for the build status
[03:10:21.433] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:21.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:68:0) after 1m0s
[03:11:21.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:68:0)
[03:11:21.516] Timer tick, asking Buildbucket for the build status
[03:11:21.806] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:21.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:69:0) after 1m0s
[03:12:21.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:69:0)
[03:12:21.886] Timer tick, asking Buildbucket for the build status
[03:12:21.993] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:21.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:70:0) after 1m0s
[03:13:22.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:70:0)
[03:13:22.017] Timer tick, asking Buildbucket for the build status
[03:13:22.084] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:22.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:71:0) after 1m0s
[03:14:22.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:71:0)
[03:14:22.113] Timer tick, asking Buildbucket for the build status
[03:14:22.386] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:22.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:72:0) after 1m0s
[03:15:22.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:72:0)
[03:15:22.474] Timer tick, asking Buildbucket for the build status
[03:15:22.792] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:22.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:73:0) after 1m0s
[03:16:22.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:73:0)
[03:16:22.905] Timer tick, asking Buildbucket for the build status
[03:16:23.206] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:23.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:74:0) after 1m0s
[03:17:23.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:74:0)
[03:17:23.259] Timer tick, asking Buildbucket for the build status
[03:17:23.338] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:23.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:75:0) after 1m0s
[03:18:23.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:75:0)
[03:18:23.321] Timer tick, asking Buildbucket for the build status
[03:18:23.552] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:23.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:76:0) after 1m0s
[03:19:23.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:76:0)
[03:19:23.574] Timer tick, asking Buildbucket for the build status
[03:19:23.674] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:23.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:77:0) after 1m0s
[03:20:23.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:77:0)
[03:20:23.722] Timer tick, asking Buildbucket for the build status
[03:20:23.812] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:23.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:78:0) after 1m0s
[03:21:23.831] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:78:0)
[03:21:23.831] Timer tick, asking Buildbucket for the build status
[03:21:23.938] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:23.938] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:79:0) after 1m0s
[03:22:23.958] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:79:0)
[03:22:23.958] Timer tick, asking Buildbucket for the build status
[03:22:24.126] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:24.126] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:80:0) after 1m0s
[03:23:24.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:80:0)
[03:23:24.152] Timer tick, asking Buildbucket for the build status
[03:23:24.302] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:24.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:81:0) after 1m0s
[03:24:24.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:81:0)
[03:24:24.365] Timer tick, asking Buildbucket for the build status
[03:24:24.527] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:24.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:82:0) after 1m0s
[03:25:24.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:82:0)
[03:25:24.552] Timer tick, asking Buildbucket for the build status
[03:25:24.677] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:24.677] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:83:0) after 1m0s
[03:26:24.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:83:0)
[03:26:24.730] Timer tick, asking Buildbucket for the build status
[03:26:24.979] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:24.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:84:0) after 1m0s
[03:27:25.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:84:0)
[03:27:25.012] Timer tick, asking Buildbucket for the build status
[03:27:25.112] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:25.112] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:85:0) after 1m0s
[03:28:25.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:85:0)
[03:28:25.157] Timer tick, asking Buildbucket for the build status
[03:28:25.235] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:25.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:86:0) after 1m0s
[03:29:25.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:86:0)
[03:29:25.236] Timer tick, asking Buildbucket for the build status
[03:29:25.355] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:25.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:87:0) after 1m0s
[03:30:25.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:87:0)
[03:30:25.433] Timer tick, asking Buildbucket for the build status
[03:30:25.722] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:25.722] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:88:0) after 1m0s
[03:31:25.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:88:0)
[03:31:25.767] Timer tick, asking Buildbucket for the build status
[03:31:25.841] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:25.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:89:0) after 1m0s
[03:32:25.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:89:0)
[03:32:25.902] Timer tick, asking Buildbucket for the build status
[03:32:25.988] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:25.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:90:0) after 1m0s
[03:33:26.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:90:0)
[03:33:26.049] Timer tick, asking Buildbucket for the build status
[03:33:26.146] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:26.146] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:91:0) after 1m0s
[03:34:26.197] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:91:0)
[03:34:26.197] Timer tick, asking Buildbucket for the build status
[03:34:26.311] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:26.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:92:0) after 1m0s
[03:35:26.387] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:92:0)
[03:35:26.387] Timer tick, asking Buildbucket for the build status
[03:35:26.469] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:26.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:93:0) after 1m0s
[03:36:26.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:93:0)
[03:36:26.477] Timer tick, asking Buildbucket for the build status
[03:36:26.587] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:26.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:94:0) after 1m0s
[03:37:26.611] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:94:0)
[03:37:26.611] Timer tick, asking Buildbucket for the build status
[03:37:26.709] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:26.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:95:0) after 1m0s
[03:38:26.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:95:0)
[03:38:26.767] Timer tick, asking Buildbucket for the build status
[03:38:26.850] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:26.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:96:0) after 1m0s
[03:39:26.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:96:0)
[03:39:26.941] Timer tick, asking Buildbucket for the build status
[03:39:27.220] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:27.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:97:0) after 1m0s
[03:40:27.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:97:0)
[03:40:27.287] Timer tick, asking Buildbucket for the build status
[03:40:27.538] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:27.538] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:98:0) after 1m0s
[03:41:27.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:98:0)
[03:41:27.565] Timer tick, asking Buildbucket for the build status
[03:41:27.828] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:27.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:99:0) after 1m0s
[03:42:27.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:99:0)
[03:42:27.816] Timer tick, asking Buildbucket for the build status
[03:42:28.017] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:28.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:100:0) after 1m0s
[03:43:28.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:100:0)
[03:43:28.067] Timer tick, asking Buildbucket for the build status
[03:43:28.162] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:28.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:101:0) after 1m0s
[03:44:28.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:101:0)
[03:44:28.177] Timer tick, asking Buildbucket for the build status
[03:44:28.295] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:28.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:102:0) after 1m0s
[03:45:28.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:102:0)
[03:45:28.592] Timer tick, asking Buildbucket for the build status
[03:45:28.710] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:28.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:103:0) after 1m0s
[03:46:28.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:103:0)
[03:46:28.819] Timer tick, asking Buildbucket for the build status
[03:46:28.889] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:28.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:104:0) after 1m0s
[03:47:28.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:104:0)
[03:47:28.925] Timer tick, asking Buildbucket for the build status
[03:47:29.017] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:29.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:105:0) after 1m0s
[03:48:29.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:105:0)
[03:48:29.106] Timer tick, asking Buildbucket for the build status
[03:48:29.508] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:29.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:106:0) after 1m0s
[03:49:29.555] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:106:0)
[03:49:29.555] Timer tick, asking Buildbucket for the build status
[03:49:29.996] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:29.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:107:0) after 1m0s
[03:50:29.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:107:0)
[03:50:29.996] Timer tick, asking Buildbucket for the build status
[03:50:30.098] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:30.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:108:0) after 1m0s
[03:51:30.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:108:0)
[03:51:30.158] Timer tick, asking Buildbucket for the build status
[03:51:30.226] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:30.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:109:0) after 1m0s
[03:52:30.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:109:0)
[03:52:30.249] Timer tick, asking Buildbucket for the build status
[03:52:30.359] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:30.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:110:0) after 1m0s
[03:53:30.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:110:0)
[03:53:30.380] Timer tick, asking Buildbucket for the build status
[03:53:30.464] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:30.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:111:0) after 1m0s
[03:54:30.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:111:0)
[03:54:30.509] Timer tick, asking Buildbucket for the build status
[03:54:30.808] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:30.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:112:0) after 1m0s
[03:55:30.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:112:0)
[03:55:30.852] Timer tick, asking Buildbucket for the build status
[03:55:30.926] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:30.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:113:0) after 1m0s
[03:56:30.973] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:113:0)
[03:56:30.973] Timer tick, asking Buildbucket for the build status
[03:56:31.057] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:31.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:114:0) after 1m0s
[03:57:31.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:114:0)
[03:57:31.167] Timer tick, asking Buildbucket for the build status
[03:57:31.324] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:31.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:115:0) after 1m0s
[03:58:31.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:115:0)
[03:58:31.385] Timer tick, asking Buildbucket for the build status
[03:58:31.478] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:31.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:116:0) after 1m0s
[03:59:31.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:116:0)
[03:59:31.487] Timer tick, asking Buildbucket for the build status
[03:59:31.618] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:31.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:117:0) after 1m0s
[04:00:31.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:117:0)
[04:00:31.691] Timer tick, asking Buildbucket for the build status
[04:00:31.851] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:31.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:118:0) after 1m0s
[04:01:31.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:118:0)
[04:01:31.920] Timer tick, asking Buildbucket for the build status
[04:01:32.017] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:32.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:119:0) after 1m0s
[04:02:32.055] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:119:0)
[04:02:32.055] Timer tick, asking Buildbucket for the build status
[04:02:32.258] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:32.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:120:0) after 1m0s
[04:03:32.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:120:0)
[04:03:32.313] Timer tick, asking Buildbucket for the build status
[04:03:32.404] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:32.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:121:0) after 1m0s
[04:04:32.487] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:121:0)
[04:04:32.487] Timer tick, asking Buildbucket for the build status
[04:04:32.660] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:32.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:122:0) after 1m0s
[04:05:32.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:122:0)
[04:05:32.638] Timer tick, asking Buildbucket for the build status
[04:05:32.720] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:32.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:123:0) after 1m0s
[04:06:32.722] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:123:0)
[04:06:32.722] Timer tick, asking Buildbucket for the build status
[04:06:32.799] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:32.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:124:0) after 1m0s
[04:07:32.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:124:0)
[04:07:32.897] Timer tick, asking Buildbucket for the build status
[04:07:32.977] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:32.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:125:0) after 1m0s
[04:08:33.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:125:0)
[04:08:33.101] Timer tick, asking Buildbucket for the build status
[04:08:33.206] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:33.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:126:0) after 1m0s
[04:09:33.310] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:126:0)
[04:09:33.310] Timer tick, asking Buildbucket for the build status
[04:09:33.396] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:33.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:127:0) after 1m0s
[04:10:33.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:127:0)
[04:10:33.491] Timer tick, asking Buildbucket for the build status
[04:10:33.729] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:33.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:128:0) after 1m0s
[04:11:33.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:128:0)
[04:11:33.795] Timer tick, asking Buildbucket for the build status
[04:11:33.945] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:33.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:129:0) after 1m0s
[04:12:34.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:129:0)
[04:12:34.063] Timer tick, asking Buildbucket for the build status
[04:12:34.129] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:34.130] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:130:0) after 1m0s
[04:13:34.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:130:0)
[04:13:34.165] Timer tick, asking Buildbucket for the build status
[04:13:34.275] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:34.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:131:0) after 1m0s
[04:14:34.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:131:0)
[04:14:34.360] Timer tick, asking Buildbucket for the build status
[04:14:34.462] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:34.462] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:132:0) after 1m0s
[04:15:34.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:132:0)
[04:15:34.448] Timer tick, asking Buildbucket for the build status
[04:15:34.530] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:34.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:133:0) after 1m0s
[04:16:34.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:133:0)
[04:16:34.568] Timer tick, asking Buildbucket for the build status
[04:16:34.734] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:34.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:134:0) after 1m0s
[04:17:34.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:134:0)
[04:17:34.809] Timer tick, asking Buildbucket for the build status
[04:17:34.888] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:34.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:135:0) after 1m0s
[04:18:35.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:135:0)
[04:18:35.019] Timer tick, asking Buildbucket for the build status
[04:18:35.213] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:35.214] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:136:0) after 1m0s
[04:19:35.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:136:0)
[04:19:35.365] Timer tick, asking Buildbucket for the build status
[04:19:35.475] Build 8855631144893018432: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:35.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:137:0) after 1m0s
[04:19:40.596] Received PubSub notification, asking Buildbucket for the build status
[04:19:40.681] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:35.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:137:0)
[04:20:35.538] Timer tick, asking Buildbucket for the build status
[04:20:35.634] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:35.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:139:0) after 1m0s
[04:21:35.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:139:0)
[04:21:35.683] Timer tick, asking Buildbucket for the build status
[04:21:35.784] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:35.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:140:0) after 1m0s
[04:22:35.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:140:0)
[04:22:35.801] Timer tick, asking Buildbucket for the build status
[04:22:36.104] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:36.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:141:0) after 1m0s
[04:23:36.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:141:0)
[04:23:36.203] Timer tick, asking Buildbucket for the build status
[04:23:36.297] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:36.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:142:0) after 1m0s
[04:24:36.318] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:142:0)
[04:24:36.318] Timer tick, asking Buildbucket for the build status
[04:24:36.512] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:36.512] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:143:0) after 1m0s
[04:25:36.564] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:143:0)
[04:25:36.564] Timer tick, asking Buildbucket for the build status
[04:25:36.697] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:36.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:144:0) after 1m0s
[04:26:36.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:144:0)
[04:26:36.736] Timer tick, asking Buildbucket for the build status
[04:26:36.811] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:36.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:145:0) after 1m0s
[04:27:36.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:145:0)
[04:27:36.837] Timer tick, asking Buildbucket for the build status
[04:27:36.935] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:36.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:146:0) after 1m0s
[04:28:36.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:146:0)
[04:28:36.974] Timer tick, asking Buildbucket for the build status
[04:28:37.305] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:37.305] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:147:0) after 1m0s
[04:29:37.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:147:0)
[04:29:37.390] Timer tick, asking Buildbucket for the build status
[04:29:38.098] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:38.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:148:0) after 1m0s
[04:30:38.115] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:148:0)
[04:30:38.115] Timer tick, asking Buildbucket for the build status
[04:30:38.208] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:38.208] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:149:0) after 1m0s
[04:31:38.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:149:0)
[04:31:38.244] Timer tick, asking Buildbucket for the build status
[04:31:38.352] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:38.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:150:0) after 1m0s
[04:32:38.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:150:0)
[04:32:38.404] Timer tick, asking Buildbucket for the build status
[04:32:38.685] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:38.685] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:151:0) after 1m0s
[04:33:38.714] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:151:0)
[04:33:38.714] Timer tick, asking Buildbucket for the build status
[04:33:38.811] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:38.811] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:152:0) after 1m0s
[04:34:38.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:152:0)
[04:34:38.841] Timer tick, asking Buildbucket for the build status
[04:34:38.969] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:38.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:153:0) after 1m0s
[04:35:39.108] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:153:0)
[04:35:39.108] Timer tick, asking Buildbucket for the build status
[04:35:39.205] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:39.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:154:0) after 1m0s
[04:36:39.294] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:154:0)
[04:36:39.294] Timer tick, asking Buildbucket for the build status
[04:36:39.385] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:39.385] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:155:0) after 1m0s
[04:37:39.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:155:0)
[04:37:39.384] Timer tick, asking Buildbucket for the build status
[04:37:39.476] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:39.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:156:0) after 1m0s
[04:38:39.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:156:0)
[04:38:39.505] Timer tick, asking Buildbucket for the build status
[04:38:39.732] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:39.732] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:157:0) after 1m0s
[04:39:39.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:157:0)
[04:39:39.768] Timer tick, asking Buildbucket for the build status
[04:39:39.863] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:39.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:158:0) after 1m0s
[04:40:39.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:158:0)
[04:40:39.853] Timer tick, asking Buildbucket for the build status
[04:40:39.943] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:39.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:159:0) after 1m0s
[04:41:39.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:159:0)
[04:41:39.939] Timer tick, asking Buildbucket for the build status
[04:41:40.022] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:40.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:160:0) after 1m0s
[04:42:40.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:160:0)
[04:42:40.043] Timer tick, asking Buildbucket for the build status
[04:42:40.330] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:40.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:161:0) after 1m0s
[04:43:40.378] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:161:0)
[04:43:40.378] Timer tick, asking Buildbucket for the build status
[04:43:40.451] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:40.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:162:0) after 1m0s
[04:44:40.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:162:0)
[04:44:40.478] Timer tick, asking Buildbucket for the build status
[04:44:40.860] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:40.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:163:0) after 1m0s
[04:45:40.936] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:163:0)
[04:45:40.936] Timer tick, asking Buildbucket for the build status
[04:45:41.035] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:41.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:164:0) after 1m0s
[04:46:41.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:164:0)
[04:46:41.058] Timer tick, asking Buildbucket for the build status
[04:46:41.166] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:41.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:165:0) after 1m0s
[04:47:41.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:165:0)
[04:47:41.199] Timer tick, asking Buildbucket for the build status
[04:47:41.270] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:41.270] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:166:0) after 1m0s
[04:48:41.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:166:0)
[04:48:41.301] Timer tick, asking Buildbucket for the build status
[04:48:41.407] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:41.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:167:0) after 1m0s
[04:49:41.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:167:0)
[04:49:41.502] Timer tick, asking Buildbucket for the build status
[04:49:41.621] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:41.621] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:168:0) after 1m0s
[04:50:41.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:168:0)
[04:50:41.642] Timer tick, asking Buildbucket for the build status
[04:50:41.756] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:41.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:169:0) after 1m0s
[04:51:41.781] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:169:0)
[04:51:41.781] Timer tick, asking Buildbucket for the build status
[04:51:41.945] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:41.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:170:0) after 1m0s
[04:52:42.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:170:0)
[04:52:42.021] Timer tick, asking Buildbucket for the build status
[04:52:42.106] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:42.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:171:0) after 1m0s
[04:53:42.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:171:0)
[04:53:42.153] Timer tick, asking Buildbucket for the build status
[04:53:42.514] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:42.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:172:0) after 1m0s
[04:54:42.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:172:0)
[04:54:42.538] Timer tick, asking Buildbucket for the build status
[04:54:42.652] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:42.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:173:0) after 1m0s
[04:55:42.698] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:173:0)
[04:55:42.698] Timer tick, asking Buildbucket for the build status
[04:55:42.896] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:42.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:174:0) after 1m0s
[04:56:42.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:174:0)
[04:56:42.937] Timer tick, asking Buildbucket for the build status
[04:56:43.016] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:43.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:175:0) after 1m0s
[04:57:43.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:175:0)
[04:57:43.110] Timer tick, asking Buildbucket for the build status
[04:57:43.204] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:43.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:176:0) after 1m0s
[04:58:43.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:176:0)
[04:58:43.238] Timer tick, asking Buildbucket for the build status
[04:58:43.356] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:43.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:177:0) after 1m0s
[04:59:43.418] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:177:0)
[04:59:43.418] Timer tick, asking Buildbucket for the build status
[04:59:43.928] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:43.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:178:0) after 1m0s
[05:00:44.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:178:0)
[05:00:44.001] Timer tick, asking Buildbucket for the build status
[05:00:44.348] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:44.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:179:0) after 1m0s
[05:01:44.439] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:179:0)
[05:01:44.439] Timer tick, asking Buildbucket for the build status
[05:01:44.536] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:44.536] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:180:0) after 1m0s
[05:02:44.569] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:180:0)
[05:02:44.569] Timer tick, asking Buildbucket for the build status
[05:02:44.812] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:44.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:181:0) after 1m0s
[05:03:44.907] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:181:0)
[05:03:44.907] Timer tick, asking Buildbucket for the build status
[05:03:44.996] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:44.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:182:0) after 1m0s
[05:04:45.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:182:0)
[05:04:45.020] Timer tick, asking Buildbucket for the build status
[05:04:45.486] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:45.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:183:0) after 1m0s
[05:05:45.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:183:0)
[05:05:45.509] Timer tick, asking Buildbucket for the build status
[05:05:45.597] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:45.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:184:0) after 1m0s
[05:06:45.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:184:0)
[05:06:45.623] Timer tick, asking Buildbucket for the build status
[05:06:45.892] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:45.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:185:0) after 1m0s
[05:07:45.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:185:0)
[05:07:45.899] Timer tick, asking Buildbucket for the build status
[05:07:46.022] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:46.022] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:186:0) after 1m0s
[05:08:46.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:186:0)
[05:08:46.111] Timer tick, asking Buildbucket for the build status
[05:08:46.207] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:46.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:187:0) after 1m0s
[05:09:46.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:187:0)
[05:09:46.238] Timer tick, asking Buildbucket for the build status
[05:09:46.597] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:46.597] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:188:0) after 1m0s
[05:10:46.634] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:188:0)
[05:10:46.634] Timer tick, asking Buildbucket for the build status
[05:10:46.703] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:46.703] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:189:0) after 1m0s
[05:11:46.709] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:189:0)
[05:11:46.709] Timer tick, asking Buildbucket for the build status
[05:11:46.867] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:46.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:190:0) after 1m0s
[05:12:46.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:190:0)
[05:12:46.901] Timer tick, asking Buildbucket for the build status
[05:12:46.977] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:46.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:191:0) after 1m0s
[05:13:46.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:191:0)
[05:13:46.996] Timer tick, asking Buildbucket for the build status
[05:13:47.082] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:47.082] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:192:0) after 1m0s
[05:14:47.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:192:0)
[05:14:47.059] Timer tick, asking Buildbucket for the build status
[05:14:47.464] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:47.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:193:0) after 1m0s
[05:15:47.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:193:0)
[05:15:47.552] Timer tick, asking Buildbucket for the build status
[05:15:47.663] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:47.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:194:0) after 1m0s
[05:16:47.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:194:0)
[05:16:47.651] Timer tick, asking Buildbucket for the build status
[05:16:47.728] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:47.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:195:0) after 1m0s
[05:17:47.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:195:0)
[05:17:47.734] Timer tick, asking Buildbucket for the build status
[05:17:47.827] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:47.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:196:0) after 1m0s
[05:18:47.890] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:196:0)
[05:18:47.890] Timer tick, asking Buildbucket for the build status
[05:18:47.974] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:47.974] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:197:0) after 1m0s
[05:19:48.000] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:197:0)
[05:19:48.000] Timer tick, asking Buildbucket for the build status
[05:19:48.120] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:48.120] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:198:0) after 1m0s
[05:20:48.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:198:0)
[05:20:48.148] Timer tick, asking Buildbucket for the build status
[05:20:48.296] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:48.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:199:0) after 1m0s
[05:21:48.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:199:0)
[05:21:48.357] Timer tick, asking Buildbucket for the build status
[05:21:48.475] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:48.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:200:0) after 1m0s
[05:22:48.517] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:200:0)
[05:22:48.517] Timer tick, asking Buildbucket for the build status
[05:22:48.599] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:48.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:201:0) after 1m0s
[05:23:48.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:201:0)
[05:23:48.575] Timer tick, asking Buildbucket for the build status
[05:23:48.647] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:48.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:202:0) after 1m0s
[05:24:48.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:202:0)
[05:24:48.805] Timer tick, asking Buildbucket for the build status
[05:24:49.163] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:49.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:203:0) after 1m0s
[05:25:49.201] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:203:0)
[05:25:49.201] Timer tick, asking Buildbucket for the build status
[05:25:49.302] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:49.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:204:0) after 1m0s
[05:26:49.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:204:0)
[05:26:49.328] Timer tick, asking Buildbucket for the build status
[05:26:49.425] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:49.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:205:0) after 1m0s
[05:27:49.477] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:205:0)
[05:27:49.477] Timer tick, asking Buildbucket for the build status
[05:27:49.711] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:49.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:206:0) after 1m0s
[05:28:49.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:206:0)
[05:28:49.735] Timer tick, asking Buildbucket for the build status
[05:28:50.033] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:50.033] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:207:0) after 1m0s
[05:29:50.103] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:207:0)
[05:29:50.103] Timer tick, asking Buildbucket for the build status
[05:29:50.505] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:50.505] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:208:0) after 1m0s
[05:30:50.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:208:0)
[05:30:50.562] Timer tick, asking Buildbucket for the build status
[05:30:50.800] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:50.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:209:0) after 1m0s
[05:31:50.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:209:0)
[05:31:50.829] Timer tick, asking Buildbucket for the build status
[05:31:50.903] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:50.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:210:0) after 1m0s
[05:32:50.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:210:0)
[05:32:50.937] Timer tick, asking Buildbucket for the build status
[05:32:51.191] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:51.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:211:0) after 1m0s
[05:33:51.204] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:211:0)
[05:33:51.204] Timer tick, asking Buildbucket for the build status
[05:33:51.299] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:51.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:212:0) after 1m0s
[05:34:51.361] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:212:0)
[05:34:51.361] Timer tick, asking Buildbucket for the build status
[05:34:51.496] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:51.496] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:213:0) after 1m0s
[05:35:51.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:213:0)
[05:35:51.526] Timer tick, asking Buildbucket for the build status
[05:35:51.729] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:51.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:214:0) after 1m0s
[05:36:51.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:214:0)
[05:36:51.777] Timer tick, asking Buildbucket for the build status
[05:36:51.864] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:51.864] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:215:0) after 1m0s
[05:37:51.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:215:0)
[05:37:51.856] Timer tick, asking Buildbucket for the build status
[05:37:52.251] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:52.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:216:0) after 1m0s
[05:38:52.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:216:0)
[05:38:52.285] Timer tick, asking Buildbucket for the build status
[05:38:52.359] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:52.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:217:0) after 1m0s
[05:39:52.459] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:217:0)
[05:39:52.459] Timer tick, asking Buildbucket for the build status
[05:39:52.598] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:52.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:218:0) after 1m0s
[05:40:52.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:218:0)
[05:40:52.621] Timer tick, asking Buildbucket for the build status
[05:40:52.705] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:52.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:219:0) after 1m0s
[05:41:52.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:219:0)
[05:41:52.801] Timer tick, asking Buildbucket for the build status
[05:41:52.887] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:52.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:220:0) after 1m0s
[05:42:52.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:220:0)
[05:42:52.963] Timer tick, asking Buildbucket for the build status
[05:42:53.150] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:53.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:221:0) after 1m0s
[05:43:53.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:221:0)
[05:43:53.157] Timer tick, asking Buildbucket for the build status
[05:43:53.238] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:53.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:222:0) after 1m0s
[05:44:53.268] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:222:0)
[05:44:53.268] Timer tick, asking Buildbucket for the build status
[05:44:53.433] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:53.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:223:0) after 1m0s
[05:45:53.509] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:223:0)
[05:45:53.509] Timer tick, asking Buildbucket for the build status
[05:45:53.594] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:53.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:224:0) after 1m0s
[05:46:53.612] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:224:0)
[05:46:53.612] Timer tick, asking Buildbucket for the build status
[05:46:53.710] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:53.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:225:0) after 1m0s
[05:47:53.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:225:0)
[05:47:53.735] Timer tick, asking Buildbucket for the build status
[05:47:53.827] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:53.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:226:0) after 1m0s
[05:48:53.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:226:0)
[05:48:53.863] Timer tick, asking Buildbucket for the build status
[05:48:54.137] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:54.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:227:0) after 1m0s
[05:49:54.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:227:0)
[05:49:54.168] Timer tick, asking Buildbucket for the build status
[05:49:54.254] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:54.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:228:0) after 1m0s
[05:50:54.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:228:0)
[05:50:54.304] Timer tick, asking Buildbucket for the build status
[05:50:54.399] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:54.399] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:229:0) after 1m0s
[05:51:54.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:229:0)
[05:51:54.424] Timer tick, asking Buildbucket for the build status
[05:51:54.526] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:54.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:230:0) after 1m0s
[05:52:54.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:230:0)
[05:52:54.556] Timer tick, asking Buildbucket for the build status
[05:52:54.776] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:54.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:231:0) after 1m0s
[05:53:54.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:231:0)
[05:53:54.818] Timer tick, asking Buildbucket for the build status
[05:53:54.983] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:54.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:232:0) after 1m0s
[05:54:55.021] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:232:0)
[05:54:55.021] Timer tick, asking Buildbucket for the build status
[05:54:55.219] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:55.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:233:0) after 1m0s
[05:55:55.248] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:233:0)
[05:55:55.248] Timer tick, asking Buildbucket for the build status
[05:55:55.360] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:55.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:234:0) after 1m0s
[05:56:55.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:234:0)
[05:56:55.406] Timer tick, asking Buildbucket for the build status
[05:56:55.510] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:55.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:235:0) after 1m0s
[05:57:55.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:235:0)
[05:57:55.688] Timer tick, asking Buildbucket for the build status
[05:57:55.817] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:55.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:236:0) after 1m0s
[05:58:56.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:236:0)
[05:58:56.009] Timer tick, asking Buildbucket for the build status
[05:58:56.085] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:56.085] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:237:0) after 1m0s
[05:59:56.127] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:237:0)
[05:59:56.127] Timer tick, asking Buildbucket for the build status
[05:59:56.219] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:56.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:238:0) after 1m0s
[06:00:56.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:238:0)
[06:00:56.347] Timer tick, asking Buildbucket for the build status
[06:00:56.454] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:56.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:239:0) after 1m0s
[06:01:57.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:239:0)
[06:01:57.870] Timer tick, asking Buildbucket for the build status
[06:01:57.960] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:57.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:240:0) after 1m0s
[06:02:58.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:240:0)
[06:02:58.231] Timer tick, asking Buildbucket for the build status
[06:02:58.330] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:58.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:241:0) after 1m0s
[06:03:58.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:241:0)
[06:03:58.381] Timer tick, asking Buildbucket for the build status
[06:03:58.700] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:58.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:242:0) after 1m0s
[06:04:58.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:242:0)
[06:04:58.801] Timer tick, asking Buildbucket for the build status
[06:04:59.077] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:59.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:243:0) after 1m0s
[06:05:59.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:243:0)
[06:05:59.186] Timer tick, asking Buildbucket for the build status
[06:05:59.290] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:59.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:244:0) after 1m0s
[06:06:59.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:244:0)
[06:06:59.529] Timer tick, asking Buildbucket for the build status
[06:06:59.641] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:59.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:245:0) after 1m0s
[06:07:59.675] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:245:0)
[06:07:59.675] Timer tick, asking Buildbucket for the build status
[06:07:59.769] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:59.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:246:0) after 1m0s
[06:08:59.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:246:0)
[06:08:59.795] Timer tick, asking Buildbucket for the build status
[06:08:59.873] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:59.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:247:0) after 1m0s
[06:10:00.327] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:247:0)
[06:10:00.327] Timer tick, asking Buildbucket for the build status
[06:10:00.442] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:00.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:248:0) after 1m0s
[06:11:00.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:248:0)
[06:11:00.554] Timer tick, asking Buildbucket for the build status
[06:11:03.232] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:03.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:249:0) after 1m0s
[06:12:03.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:249:0)
[06:12:03.238] Timer tick, asking Buildbucket for the build status
[06:12:03.338] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:03.338] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:250:0) after 1m0s
[06:13:03.543] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:250:0)
[06:13:03.543] Timer tick, asking Buildbucket for the build status
[06:13:03.617] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:03.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:251:0) after 1m0s
[06:14:03.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:251:0)
[06:14:03.787] Timer tick, asking Buildbucket for the build status
[06:14:03.991] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:03.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:252:0) after 1m0s
[06:15:04.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:252:0)
[06:15:04.119] Timer tick, asking Buildbucket for the build status
[06:15:04.223] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:04.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:253:0) after 1m0s
[06:16:04.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:253:0)
[06:16:04.499] Timer tick, asking Buildbucket for the build status
[06:16:04.582] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:04.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:254:0) after 1m0s
[06:17:04.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:254:0)
[06:17:04.784] Timer tick, asking Buildbucket for the build status
[06:17:04.861] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:04.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:255:0) after 1m0s
[06:18:05.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:255:0)
[06:18:05.151] Timer tick, asking Buildbucket for the build status
[06:18:05.569] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:05.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:256:0) after 1m0s
[06:19:05.621] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:256:0)
[06:19:05.621] Timer tick, asking Buildbucket for the build status
[06:19:05.719] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:05.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:257:0) after 1m0s
[06:20:05.849] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:257:0)
[06:20:05.849] Timer tick, asking Buildbucket for the build status
[06:20:06.123] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:06.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:258:0) after 1m0s
[06:21:06.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:258:0)
[06:21:06.358] Timer tick, asking Buildbucket for the build status
[06:21:06.515] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:06.515] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:259:0) after 1m0s
[06:22:06.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:259:0)
[06:22:06.652] Timer tick, asking Buildbucket for the build status
[06:22:07.018] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:07.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:260:0) after 1m0s
[06:23:07.182] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:260:0)
[06:23:07.182] Timer tick, asking Buildbucket for the build status
[06:23:07.344] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:07.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:261:0) after 1m0s
[06:24:07.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:261:0)
[06:24:07.453] Timer tick, asking Buildbucket for the build status
[06:24:07.586] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:07.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:262:0) after 1m0s
[06:25:07.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:262:0)
[06:25:07.704] Timer tick, asking Buildbucket for the build status
[06:25:07.833] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:07.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:263:0) after 1m0s
[06:26:07.969] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:263:0)
[06:26:07.969] Timer tick, asking Buildbucket for the build status
[06:26:08.209] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:08.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:264:0) after 1m0s
[06:27:08.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:264:0)
[06:27:08.344] Timer tick, asking Buildbucket for the build status
[06:27:08.425] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:08.425] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:265:0) after 1m0s
[06:28:08.513] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:265:0)
[06:28:08.513] Timer tick, asking Buildbucket for the build status
[06:28:08.804] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:08.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:266:0) after 1m0s
[06:29:08.928] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:266:0)
[06:29:08.928] Timer tick, asking Buildbucket for the build status
[06:29:09.064] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:09.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:267:0) after 1m0s
[06:30:09.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:267:0)
[06:30:09.085] Timer tick, asking Buildbucket for the build status
[06:30:09.445] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:09.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:268:0) after 1m0s
[06:31:09.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:268:0)
[06:31:09.470] Timer tick, asking Buildbucket for the build status
[06:31:09.556] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:09.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:269:0) after 1m0s
[06:32:09.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:269:0)
[06:32:09.544] Timer tick, asking Buildbucket for the build status
[06:32:09.805] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:09.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:270:0) after 1m0s
[06:33:09.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:270:0)
[06:33:09.871] Timer tick, asking Buildbucket for the build status
[06:33:09.949] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:09.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:271:0) after 1m0s
[06:34:09.961] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:271:0)
[06:34:09.961] Timer tick, asking Buildbucket for the build status
[06:34:10.037] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:10.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:272:0) after 1m0s
[06:35:10.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:272:0)
[06:35:10.063] Timer tick, asking Buildbucket for the build status
[06:35:10.172] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:10.172] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:273:0) after 1m0s
[06:36:10.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:273:0)
[06:36:10.223] Timer tick, asking Buildbucket for the build status
[06:36:10.527] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:10.527] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:274:0) after 1m0s
[06:37:10.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:274:0)
[06:37:10.594] Timer tick, asking Buildbucket for the build status
[06:37:10.735] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:10.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:275:0) after 1m0s
[06:38:10.904] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:275:0)
[06:38:10.904] Timer tick, asking Buildbucket for the build status
[06:38:11.038] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:11.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:276:0) after 1m0s
[06:39:11.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:276:0)
[06:39:11.107] Timer tick, asking Buildbucket for the build status
[06:39:11.196] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:11.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:277:0) after 1m0s
[06:40:11.283] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:277:0)
[06:40:11.283] Timer tick, asking Buildbucket for the build status
[06:40:11.391] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:11.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:278:0) after 1m0s
[06:41:11.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:278:0)
[06:41:11.465] Timer tick, asking Buildbucket for the build status
[06:41:11.559] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:11.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:279:0) after 1m0s
[06:42:11.614] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:279:0)
[06:42:11.614] Timer tick, asking Buildbucket for the build status
[06:42:11.695] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:11.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:280:0) after 1m0s
[06:43:11.783] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:280:0)
[06:43:11.783] Timer tick, asking Buildbucket for the build status
[06:43:11.878] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:11.878] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:281:0) after 1m0s
[06:44:11.935] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:281:0)
[06:44:11.935] Timer tick, asking Buildbucket for the build status
[06:44:12.054] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:12.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:282:0) after 1m0s
[06:45:12.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:282:0)
[06:45:12.049] Timer tick, asking Buildbucket for the build status
[06:45:12.191] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:12.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:283:0) after 1m0s
[06:46:12.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:283:0)
[06:46:12.240] Timer tick, asking Buildbucket for the build status
[06:46:12.319] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:12.319] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:284:0) after 1m0s
[06:47:12.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:284:0)
[06:47:12.409] Timer tick, asking Buildbucket for the build status
[06:47:12.502] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:12.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:285:0) after 1m0s
[06:48:12.554] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:285:0)
[06:48:12.554] Timer tick, asking Buildbucket for the build status
[06:48:12.706] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:12.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:286:0) after 1m0s
[06:49:12.718] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:286:0)
[06:49:12.718] Timer tick, asking Buildbucket for the build status
[06:49:12.827] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:12.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:287:0) after 1m0s
[06:50:12.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:287:0)
[06:50:12.924] Timer tick, asking Buildbucket for the build status
[06:50:13.032] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:13.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:288:0) after 1m0s
[06:51:13.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:288:0)
[06:51:13.063] Timer tick, asking Buildbucket for the build status
[06:51:13.306] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:13.306] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:289:0) after 1m0s
[06:52:13.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:289:0)
[06:52:13.390] Timer tick, asking Buildbucket for the build status
[06:52:13.484] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:13.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:290:0) after 1m0s
[06:53:13.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:290:0)
[06:53:13.566] Timer tick, asking Buildbucket for the build status
[06:53:13.697] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:13.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:291:0) after 1m0s
[06:54:13.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:291:0)
[06:54:13.794] Timer tick, asking Buildbucket for the build status
[06:54:13.997] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:13.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:292:0) after 1m0s
[06:55:14.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:292:0)
[06:55:14.130] Timer tick, asking Buildbucket for the build status
[06:55:14.250] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:14.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:293:0) after 1m0s
[06:56:14.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:293:0)
[06:56:14.390] Timer tick, asking Buildbucket for the build status
[06:56:14.493] Build 8855631144893018432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:14.493] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_64:9021061207248333552:294:0) after 1m0s
[06:56:25.637] Received PubSub notification, asking Buildbucket for the build status
[06:56:26.186] Build 8855631144893018432: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:56:26.186] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1613026585402735",
    "created_by": "project:nacl",
    "created_ts": "1613009043756066",
    "failure_reason": "BUILD_FAILURE",
    "id": "8855631144893018432",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"title\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"title\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-1-0vnt\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_64\", \"buildnumber\": 5127, \"got_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"builder_d982c0762990b9abf737d190051a0a3bd67b51c14564e55713aea0114e04ab24_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-1-0vnt\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1613017179497240",
    "status": "COMPLETED",
    "status_changed_ts": "1613017180093764",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_64/5127",
      "builder:linux-pnacl-x86_64-tests-x86_64",
      "buildset:commit/git/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/82ac8c0a6f0d3ffc843c693dd5149a356b866ae5",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021061207248333552",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8855631144893018432/+/annotations",
      "swarming_tag:luci_project:nacl",
      "swarming_tag:os:Linux",
      "swarming_tag:recipe_name:nacl",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:51a7a664b7eb2e10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1613026585465823",
    "url": "https://ci.chromium.org/b/8855631144893018432",
    "utcnow_ts": "1613026586161404"
  }
}
[06:56:26.186] Invocation finished in 4h52m24.059860504s with status FAILED