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

• ffed96d1528df9f114a30b14d4eb06120c8fb022 (4 years ago)

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

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

[03:23:50.330] New invocation is queued and will start shortly
[03:23:51.727] Starting the invocation (attempt 1)
[03:23:51.763] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:23:51.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:23:51.763] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9050409603584489168",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-x86_32\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"title\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64-tests-x86_32",
    "scheduler_invocation_id:9050409603584489168",
    "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
    "user_agent:luci-scheduler",
    "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "gitiles_ref:refs/heads/master"
  ]
}
[03:23:52.411] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585020231836924",
    "id": "8884979541340323072",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585020232279591",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5147",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050409603584489168",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884979541340323072/+/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": "1585020232279714",
    "url": "https://ci.chromium.org/b/8884979541340323072",
    "utcnow_ts": "1585020232405149"
  }
}
[03:23:52.412] Task URL: https://ci.chromium.org/b/8884979541340323072
[03:23:52.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:2:0) after 1m0s
[03:24:52.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:2:0)
[03:24:52.411] Timer tick, asking Buildbucket for the build status
[03:24:52.546] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:52.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:3:0) after 1m0s
[03:25:52.574] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:3:0)
[03:25:52.574] Timer tick, asking Buildbucket for the build status
[03:25:53.101] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:53.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:4:0) after 1m0s
[03:26:53.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:4:0)
[03:26:53.224] Timer tick, asking Buildbucket for the build status
[03:26:53.340] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:53.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:5:0) after 1m0s
[03:27:53.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:5:0)
[03:27:53.453] Timer tick, asking Buildbucket for the build status
[03:27:53.770] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:53.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:6:0) after 1m0s
[03:28:53.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:6:0)
[03:28:53.795] Timer tick, asking Buildbucket for the build status
[03:28:53.902] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:53.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:7:0) after 1m0s
[03:29:53.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:7:0)
[03:29:53.915] Timer tick, asking Buildbucket for the build status
[03:29:54.368] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:54.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:8:0) after 1m0s
[03:30:54.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:8:0)
[03:30:54.386] Timer tick, asking Buildbucket for the build status
[03:30:54.516] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:54.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:9:0) after 1m0s
[03:31:54.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:9:0)
[03:31:54.517] Timer tick, asking Buildbucket for the build status
[03:31:54.629] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:54.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:10:0) after 1m0s
[03:32:54.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:10:0)
[03:32:54.658] Timer tick, asking Buildbucket for the build status
[03:32:54.738] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:54.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:11:0) after 1m0s
[03:33:54.763] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:11:0)
[03:33:54.763] Timer tick, asking Buildbucket for the build status
[03:33:54.872] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:54.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:12:0) after 1m0s
[03:34:54.925] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:12:0)
[03:34:54.925] Timer tick, asking Buildbucket for the build status
[03:34:55.202] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:55.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:13:0) after 1m0s
[03:35:55.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:13:0)
[03:35:55.220] Timer tick, asking Buildbucket for the build status
[03:35:55.344] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:55.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:14:0) after 1m0s
[03:36:55.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:14:0)
[03:36:55.399] Timer tick, asking Buildbucket for the build status
[03:36:55.824] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:55.824] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:15:0) after 1m0s
[03:37:55.922] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:15:0)
[03:37:55.922] Timer tick, asking Buildbucket for the build status
[03:37:56.141] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:56.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:16:0) after 1m0s
[03:38:56.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:16:0)
[03:38:56.320] Timer tick, asking Buildbucket for the build status
[03:38:56.448] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:56.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:17:0) after 1m0s
[03:39:56.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:17:0)
[03:39:56.446] Timer tick, asking Buildbucket for the build status
[03:39:57.187] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:57.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:18:0) after 1m0s
[03:40:57.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:18:0)
[03:40:57.250] Timer tick, asking Buildbucket for the build status
[03:40:57.644] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:57.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:19:0) after 1m0s
[03:41:57.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:19:0)
[03:41:57.856] Timer tick, asking Buildbucket for the build status
[03:41:57.959] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:57.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:20:0) after 1m0s
[03:42:58.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:20:0)
[03:42:58.089] Timer tick, asking Buildbucket for the build status
[03:42:58.167] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:58.167] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:21:0) after 1m0s
[03:43:58.234] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:21:0)
[03:43:58.234] Timer tick, asking Buildbucket for the build status
[03:43:58.374] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:58.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:22:0) after 1m0s
[03:44:58.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:22:0)
[03:44:58.432] Timer tick, asking Buildbucket for the build status
[03:44:58.611] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:58.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:23:0) after 1m0s
[03:45:58.826] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:23:0)
[03:45:58.826] Timer tick, asking Buildbucket for the build status
[03:45:58.928] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:58.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:24:0) after 1m0s
[03:46:59.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:24:0)
[03:46:59.017] Timer tick, asking Buildbucket for the build status
[03:46:59.198] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:59.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:25:0) after 1m0s
[03:47:59.279] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:25:0)
[03:47:59.279] Timer tick, asking Buildbucket for the build status
[03:47:59.434] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:59.434] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:26:0) after 1m0s
[03:48:59.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:26:0)
[03:48:59.489] Timer tick, asking Buildbucket for the build status
[03:48:59.678] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:59.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:27:0) after 1m0s
[03:49:59.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:27:0)
[03:49:59.741] Timer tick, asking Buildbucket for the build status
[03:49:59.887] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:59.887] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:28:0) after 1m0s
[03:50:59.965] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:28:0)
[03:50:59.965] Timer tick, asking Buildbucket for the build status
[03:51:00.103] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:00.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:29:0) after 1m0s
[03:52:00.257] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:29:0)
[03:52:00.257] Timer tick, asking Buildbucket for the build status
[03:52:00.369] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:00.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:30:0) after 1m0s
[03:53:00.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:30:0)
[03:53:00.415] Timer tick, asking Buildbucket for the build status
[03:53:00.601] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:00.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:31:0) after 1m0s
[03:54:00.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:31:0)
[03:54:00.683] Timer tick, asking Buildbucket for the build status
[03:54:00.794] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:00.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:32:0) after 1m0s
[03:55:00.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:32:0)
[03:55:00.814] Timer tick, asking Buildbucket for the build status
[03:55:00.909] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:00.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:33:0) after 1m0s
[03:56:00.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:33:0)
[03:56:00.913] Timer tick, asking Buildbucket for the build status
[03:56:01.373] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:56:01.373] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:34:0) after 1m0s
[03:57:01.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:34:0)
[03:57:01.482] Timer tick, asking Buildbucket for the build status
[03:57:01.587] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:01.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:35:0) after 1m0s
[03:58:01.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:35:0)
[03:58:01.690] Timer tick, asking Buildbucket for the build status
[03:58:02.065] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:02.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:36:0) after 1m0s
[03:59:02.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:36:0)
[03:59:02.134] Timer tick, asking Buildbucket for the build status
[03:59:02.292] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:02.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:37:0) after 1m0s
[04:00:02.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:37:0)
[04:00:02.463] Timer tick, asking Buildbucket for the build status
[04:00:02.847] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:02.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:38:0) after 1m0s
[04:01:02.984] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:38:0)
[04:01:02.984] Timer tick, asking Buildbucket for the build status
[04:01:03.168] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:01:03.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:39:0) after 1m0s
[04:02:03.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:39:0)
[04:02:03.147] Timer tick, asking Buildbucket for the build status
[04:02:03.539] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:02:03.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:40:0) after 1m0s
[04:03:03.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:40:0)
[04:03:03.590] Timer tick, asking Buildbucket for the build status
[04:03:03.749] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:03:03.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:41:0) after 1m0s
[04:04:03.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:41:0)
[04:04:03.769] Timer tick, asking Buildbucket for the build status
[04:04:04.109] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:04:04.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:42:0) after 1m0s
[04:05:04.174] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:42:0)
[04:05:04.174] Timer tick, asking Buildbucket for the build status
[04:05:04.260] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:05:04.260] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:43:0) after 1m0s
[04:06:04.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:43:0)
[04:06:04.236] Timer tick, asking Buildbucket for the build status
[04:06:04.357] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:06:04.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:44:0) after 1m0s
[04:07:04.469] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:44:0)
[04:07:04.469] Timer tick, asking Buildbucket for the build status
[04:07:04.650] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:07:04.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:45:0) after 1m0s
[04:08:04.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:45:0)
[04:08:04.732] Timer tick, asking Buildbucket for the build status
[04:08:04.855] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:04.855] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:46:0) after 1m0s
[04:09:05.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:46:0)
[04:09:05.058] Timer tick, asking Buildbucket for the build status
[04:09:05.226] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:05.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:47:0) after 1m0s
[04:10:05.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:47:0)
[04:10:05.442] Timer tick, asking Buildbucket for the build status
[04:10:05.632] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:05.632] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:48:0) after 1m0s
[04:11:05.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:48:0)
[04:11:05.745] Timer tick, asking Buildbucket for the build status
[04:11:05.871] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:05.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:49:0) after 1m0s
[04:12:05.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:49:0)
[04:12:05.895] Timer tick, asking Buildbucket for the build status
[04:12:06.141] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:06.141] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:50:0) after 1m0s
[04:13:06.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:50:0)
[04:13:06.245] Timer tick, asking Buildbucket for the build status
[04:13:06.360] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:13:06.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:51:0) after 1m0s
[04:14:06.515] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:51:0)
[04:14:06.515] Timer tick, asking Buildbucket for the build status
[04:14:06.638] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:14:06.638] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:52:0) after 1m0s
[04:15:06.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:52:0)
[04:15:06.751] Timer tick, asking Buildbucket for the build status
[04:15:06.897] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:15:06.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:53:0) after 1m0s
[04:16:06.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:53:0)
[04:16:06.923] Timer tick, asking Buildbucket for the build status
[04:16:07.034] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:16:07.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:54:0) after 1m0s
[04:17:07.109] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:54:0)
[04:17:07.109] Timer tick, asking Buildbucket for the build status
[04:17:07.248] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:17:07.248] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:55:0) after 1m0s
[04:18:07.236] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:55:0)
[04:18:07.237] Timer tick, asking Buildbucket for the build status
[04:18:09.636] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:18:09.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:56:0) after 1m0s
[04:19:09.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:56:0)
[04:19:09.648] Timer tick, asking Buildbucket for the build status
[04:19:09.738] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:19:09.738] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:57:0) after 1m0s
[04:20:09.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:57:0)
[04:20:09.873] Timer tick, asking Buildbucket for the build status
[04:20:10.028] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:20:10.028] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:58:0) after 1m0s
[04:21:10.066] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:58:0)
[04:21:10.066] Timer tick, asking Buildbucket for the build status
[04:21:10.197] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:21:10.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:59:0) after 1m0s
[04:22:10.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:59:0)
[04:22:10.242] Timer tick, asking Buildbucket for the build status
[04:22:10.353] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:22:10.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:60:0) after 1m0s
[04:23:10.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:60:0)
[04:23:10.764] Timer tick, asking Buildbucket for the build status
[04:23:10.888] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:23:10.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:61:0) after 1m0s
[04:24:10.959] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:61:0)
[04:24:10.959] Timer tick, asking Buildbucket for the build status
[04:24:11.087] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:24:11.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:62:0) after 1m0s
[04:25:11.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:62:0)
[04:25:11.098] Timer tick, asking Buildbucket for the build status
[04:25:11.184] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:25:11.184] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:63:0) after 1m0s
[04:26:11.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:63:0)
[04:26:11.345] Timer tick, asking Buildbucket for the build status
[04:26:11.472] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:26:11.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:64:0) after 1m0s
[04:27:11.576] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:64:0)
[04:27:11.576] Timer tick, asking Buildbucket for the build status
[04:27:11.667] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:27:11.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:65:0) after 1m0s
[04:28:11.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:65:0)
[04:28:11.839] Timer tick, asking Buildbucket for the build status
[04:28:11.897] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:28:11.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:66:0) after 1m0s
[04:29:11.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:66:0)
[04:29:11.950] Timer tick, asking Buildbucket for the build status
[04:29:12.051] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:29:12.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:67:0) after 1m0s
[04:30:12.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:67:0)
[04:30:12.238] Timer tick, asking Buildbucket for the build status
[04:30:12.437] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:30:12.437] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:68:0) after 1m0s
[04:31:12.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:68:0)
[04:31:12.474] Timer tick, asking Buildbucket for the build status
[04:31:12.586] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:31:12.586] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:69:0) after 1m0s
[04:32:12.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:69:0)
[04:32:12.645] Timer tick, asking Buildbucket for the build status
[04:32:12.800] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:32:12.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:70:0) after 1m0s
[04:33:12.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:70:0)
[04:33:12.846] Timer tick, asking Buildbucket for the build status
[04:33:12.976] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:33:12.976] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:71:0) after 1m0s
[04:34:13.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:71:0)
[04:34:13.018] Timer tick, asking Buildbucket for the build status
[04:34:13.211] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:34:13.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:72:0) after 1m0s
[04:35:13.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:72:0)
[04:35:13.276] Timer tick, asking Buildbucket for the build status
[04:35:13.393] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:35:13.394] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:73:0) after 1m0s
[04:36:13.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:73:0)
[04:36:13.408] Timer tick, asking Buildbucket for the build status
[04:36:13.550] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:36:13.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:74:0) after 1m0s
[04:37:13.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:74:0)
[04:37:13.577] Timer tick, asking Buildbucket for the build status
[04:37:13.729] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:37:13.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:75:0) after 1m0s
[04:38:13.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:75:0)
[04:38:13.754] Timer tick, asking Buildbucket for the build status
[04:38:13.852] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:38:13.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:76:0) after 1m0s
[04:39:13.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:76:0)
[04:39:13.927] Timer tick, asking Buildbucket for the build status
[04:39:14.352] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:39:14.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:77:0) after 1m0s
[04:40:14.402] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:77:0)
[04:40:14.402] Timer tick, asking Buildbucket for the build status
[04:40:14.521] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:40:14.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:78:0) after 1m0s
[04:41:14.623] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:78:0)
[04:41:14.623] Timer tick, asking Buildbucket for the build status
[04:41:15.253] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:41:15.253] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:79:0) after 1m0s
[04:42:15.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:79:0)
[04:42:15.350] Timer tick, asking Buildbucket for the build status
[04:42:15.617] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:42:15.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:80:0) after 1m0s
[04:43:15.674] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:80:0)
[04:43:15.674] Timer tick, asking Buildbucket for the build status
[04:43:16.411] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:43:16.412] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:81:0) after 1m0s
[04:44:16.453] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:81:0)
[04:44:16.453] Timer tick, asking Buildbucket for the build status
[04:44:16.580] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:44:16.580] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:82:0) after 1m0s
[04:45:16.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:82:0)
[04:45:16.602] Timer tick, asking Buildbucket for the build status
[04:45:16.720] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:45:16.720] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:83:0) after 1m0s
[04:46:16.792] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:83:0)
[04:46:16.793] Timer tick, asking Buildbucket for the build status
[04:46:16.917] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:46:16.917] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:84:0) after 1m0s
[04:47:16.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:84:0)
[04:47:16.986] Timer tick, asking Buildbucket for the build status
[04:47:17.080] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:17.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:85:0) after 1m0s
[04:48:17.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:85:0)
[04:48:17.130] Timer tick, asking Buildbucket for the build status
[04:48:17.564] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:17.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:86:0) after 1m0s
[04:49:17.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:86:0)
[04:49:17.592] Timer tick, asking Buildbucket for the build status
[04:49:17.710] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:17.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:87:0) after 1m0s
[04:50:17.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:87:0)
[04:50:17.752] Timer tick, asking Buildbucket for the build status
[04:50:17.866] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:17.866] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:88:0) after 1m0s
[04:51:17.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:88:0)
[04:51:17.879] Timer tick, asking Buildbucket for the build status
[04:51:17.990] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:17.990] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:89:0) after 1m0s
[04:52:18.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:89:0)
[04:52:18.024] Timer tick, asking Buildbucket for the build status
[04:52:18.151] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:52:18.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:90:0) after 1m0s
[04:53:18.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:90:0)
[04:53:18.172] Timer tick, asking Buildbucket for the build status
[04:53:18.311] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:53:18.311] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:91:0) after 1m0s
[04:54:18.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:91:0)
[04:54:18.411] Timer tick, asking Buildbucket for the build status
[04:54:18.781] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:54:18.781] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:92:0) after 1m0s
[04:55:18.912] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:92:0)
[04:55:18.912] Timer tick, asking Buildbucket for the build status
[04:55:19.017] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:55:19.017] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:93:0) after 1m0s
[04:56:19.009] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:93:0)
[04:56:19.009] Timer tick, asking Buildbucket for the build status
[04:56:20.432] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:56:20.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:94:0) after 1m0s
[04:57:20.613] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:94:0)
[04:57:20.613] Timer tick, asking Buildbucket for the build status
[04:57:20.721] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:57:20.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:95:0) after 1m0s
[04:58:20.753] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:95:0)
[04:58:20.753] Timer tick, asking Buildbucket for the build status
[04:58:20.863] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:58:20.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:96:0) after 1m0s
[04:59:20.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:96:0)
[04:59:20.891] Timer tick, asking Buildbucket for the build status
[04:59:20.991] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:59:20.991] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:97:0) after 1m0s
[05:00:21.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:97:0)
[05:00:21.078] Timer tick, asking Buildbucket for the build status
[05:00:21.200] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:00:21.200] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:98:0) after 1m0s
[05:01:21.357] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:98:0)
[05:01:21.357] Timer tick, asking Buildbucket for the build status
[05:01:21.657] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:01:21.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:99:0) after 1m0s
[05:02:22.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:99:0)
[05:02:22.328] Timer tick, asking Buildbucket for the build status
[05:02:22.446] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:02:22.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:100:0) after 1m0s
[05:03:22.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:100:0)
[05:03:22.607] Timer tick, asking Buildbucket for the build status
[05:03:22.712] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:03:22.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:101:0) after 1m0s
[05:04:22.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:101:0)
[05:04:22.920] Timer tick, asking Buildbucket for the build status
[05:04:23.006] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:04:23.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:102:0) after 1m0s
[05:05:23.389] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:102:0)
[05:05:23.389] Timer tick, asking Buildbucket for the build status
[05:05:23.484] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:05:23.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:103:0) after 1m0s
[05:06:23.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:103:0)
[05:06:23.585] Timer tick, asking Buildbucket for the build status
[05:06:23.737] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:06:23.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:104:0) after 1m0s
[05:07:23.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:104:0)
[05:07:23.824] Timer tick, asking Buildbucket for the build status
[05:07:24.131] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:07:24.131] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:105:0) after 1m0s
[05:08:24.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:105:0)
[05:08:24.198] Timer tick, asking Buildbucket for the build status
[05:08:24.299] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:08:24.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:106:0) after 1m0s
[05:09:24.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:106:0)
[05:09:24.409] Timer tick, asking Buildbucket for the build status
[05:09:24.776] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:09:24.776] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:107:0) after 1m0s
[05:10:24.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:107:0)
[05:10:24.812] Timer tick, asking Buildbucket for the build status
[05:10:25.384] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:10:25.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:108:0) after 1m0s
[05:11:25.560] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:108:0)
[05:11:25.560] Timer tick, asking Buildbucket for the build status
[05:11:26.307] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:11:26.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:109:0) after 1m0s
[05:12:26.516] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:109:0)
[05:12:26.516] Timer tick, asking Buildbucket for the build status
[05:12:26.622] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:12:26.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:110:0) after 1m0s
[05:13:26.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:110:0)
[05:13:26.636] Timer tick, asking Buildbucket for the build status
[05:13:26.756] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:13:26.756] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:111:0) after 1m0s
[05:14:27.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:111:0)
[05:14:27.046] Timer tick, asking Buildbucket for the build status
[05:14:27.215] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:14:27.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:112:0) after 1m0s
[05:15:27.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:112:0)
[05:15:27.264] Timer tick, asking Buildbucket for the build status
[05:15:27.357] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:15:27.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:113:0) after 1m0s
[05:16:27.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:113:0)
[05:16:27.381] Timer tick, asking Buildbucket for the build status
[05:16:27.455] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:16:27.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:114:0) after 1m0s
[05:17:27.434] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:114:0)
[05:17:27.434] Timer tick, asking Buildbucket for the build status
[05:17:27.593] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:17:27.593] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:115:0) after 1m0s
[05:18:27.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:115:0)
[05:18:27.615] Timer tick, asking Buildbucket for the build status
[05:18:27.863] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:18:27.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:116:0) after 1m0s
[05:19:27.919] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:116:0)
[05:19:27.919] Timer tick, asking Buildbucket for the build status
[05:19:28.048] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:19:28.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:117:0) after 1m0s
[05:20:28.070] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:117:0)
[05:20:28.070] Timer tick, asking Buildbucket for the build status
[05:20:28.238] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:20:28.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:118:0) after 1m0s
[05:21:28.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:118:0)
[05:21:28.305] Timer tick, asking Buildbucket for the build status
[05:21:28.494] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:21:28.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:119:0) after 1m0s
[05:22:28.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:119:0)
[05:22:28.524] Timer tick, asking Buildbucket for the build status
[05:22:28.653] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:22:28.653] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:120:0) after 1m0s
[05:23:28.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:120:0)
[05:23:28.745] Timer tick, asking Buildbucket for the build status
[05:23:28.847] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:23:28.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:121:0) after 1m0s
[05:24:28.952] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:121:0)
[05:24:28.952] Timer tick, asking Buildbucket for the build status
[05:24:29.071] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:24:29.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:122:0) after 1m0s
[05:25:29.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:122:0)
[05:25:29.874] Timer tick, asking Buildbucket for the build status
[05:25:29.978] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:25:29.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:123:0) after 1m0s
[05:26:30.035] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:123:0)
[05:26:30.035] Timer tick, asking Buildbucket for the build status
[05:26:30.782] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:30.782] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:124:0) after 1m0s
[05:27:30.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:124:0)
[05:27:30.874] Timer tick, asking Buildbucket for the build status
[05:27:30.997] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:30.997] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:125:0) after 1m0s
[05:28:31.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:125:0)
[05:28:31.329] Timer tick, asking Buildbucket for the build status
[05:28:31.421] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:31.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:126:0) after 1m0s
[05:29:31.592] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:126:0)
[05:29:31.592] Timer tick, asking Buildbucket for the build status
[05:29:31.799] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:31.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:127:0) after 1m0s
[05:30:31.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:127:0)
[05:30:31.869] Timer tick, asking Buildbucket for the build status
[05:30:32.019] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:30:32.019] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:128:0) after 1m0s
[05:31:32.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:128:0)
[05:31:32.142] Timer tick, asking Buildbucket for the build status
[05:31:32.292] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:32.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:129:0) after 1m0s
[05:32:32.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:129:0)
[05:32:32.364] Timer tick, asking Buildbucket for the build status
[05:32:32.558] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:32.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:130:0) after 1m0s
[05:33:32.558] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:130:0)
[05:33:32.558] Timer tick, asking Buildbucket for the build status
[05:33:32.695] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:33:32.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:131:0) after 1m0s
[05:34:32.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:131:0)
[05:34:32.719] Timer tick, asking Buildbucket for the build status
[05:34:32.815] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:34:32.815] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:132:0) after 1m0s
[05:35:32.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:132:0)
[05:35:32.891] Timer tick, asking Buildbucket for the build status
[05:35:33.137] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:35:33.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:133:0) after 1m0s
[05:36:33.224] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:133:0)
[05:36:33.224] Timer tick, asking Buildbucket for the build status
[05:36:34.302] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:36:34.302] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:134:0) after 1m0s
[05:37:34.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:134:0)
[05:37:34.463] Timer tick, asking Buildbucket for the build status
[05:37:34.779] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:37:34.779] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:135:0) after 1m0s
[05:38:34.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:135:0)
[05:38:34.926] Timer tick, asking Buildbucket for the build status
[05:38:35.088] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:38:35.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:136:0) after 1m0s
[05:39:35.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:136:0)
[05:39:35.245] Timer tick, asking Buildbucket for the build status
[05:39:35.332] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:39:35.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:137:0) after 1m0s
[05:40:35.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:137:0)
[05:40:35.585] Timer tick, asking Buildbucket for the build status
[05:40:35.741] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:40:35.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:138:0) after 1m0s
[05:41:35.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:138:0)
[05:41:35.795] Timer tick, asking Buildbucket for the build status
[05:41:36.104] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:41:36.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:139:0) after 1m0s
[05:42:36.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:139:0)
[05:42:36.199] Timer tick, asking Buildbucket for the build status
[05:42:36.350] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:42:36.350] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:140:0) after 1m0s
[05:43:36.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:140:0)
[05:43:36.375] Timer tick, asking Buildbucket for the build status
[05:43:36.472] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:43:36.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:141:0) after 1m0s
[05:44:36.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:141:0)
[05:44:36.551] Timer tick, asking Buildbucket for the build status
[05:44:36.714] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:44:36.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:142:0) after 1m0s
[05:45:36.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:142:0)
[05:45:36.735] Timer tick, asking Buildbucket for the build status
[05:45:36.841] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:45:36.841] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:143:0) after 1m0s
[05:46:36.934] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:143:0)
[05:46:36.934] Timer tick, asking Buildbucket for the build status
[05:46:37.077] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:46:37.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:144:0) after 1m0s
[05:47:37.123] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:144:0)
[05:47:37.123] Timer tick, asking Buildbucket for the build status
[05:47:37.230] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:47:37.230] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:145:0) after 1m0s
[05:48:37.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:145:0)
[05:48:37.301] Timer tick, asking Buildbucket for the build status
[05:48:37.411] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:48:37.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:146:0) after 1m0s
[05:49:37.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:146:0)
[05:49:37.456] Timer tick, asking Buildbucket for the build status
[05:49:37.854] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:49:37.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:147:0) after 1m0s
[05:50:37.883] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:147:0)
[05:50:37.883] Timer tick, asking Buildbucket for the build status
[05:50:38.041] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:50:38.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:148:0) after 1m0s
[05:51:38.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:148:0)
[05:51:38.039] Timer tick, asking Buildbucket for the build status
[05:51:38.159] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:51:38.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:149:0) after 1m0s
[05:52:38.185] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:149:0)
[05:52:38.185] Timer tick, asking Buildbucket for the build status
[05:52:38.359] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:52:38.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:150:0) after 1m0s
[05:53:38.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:150:0)
[05:53:38.397] Timer tick, asking Buildbucket for the build status
[05:53:38.539] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:53:38.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:151:0) after 1m0s
[05:54:38.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:151:0)
[05:54:38.700] Timer tick, asking Buildbucket for the build status
[05:54:38.798] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:54:38.798] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:152:0) after 1m0s
[05:55:38.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:152:0)
[05:55:38.873] Timer tick, asking Buildbucket for the build status
[05:55:42.055] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:55:42.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:153:0) after 1m0s
[05:56:42.356] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:153:0)
[05:56:42.356] Timer tick, asking Buildbucket for the build status
[05:56:42.674] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:56:42.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:154:0) after 1m0s
[05:57:42.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:154:0)
[05:57:42.830] Timer tick, asking Buildbucket for the build status
[05:57:42.921] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:42.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:155:0) after 1m0s
[05:58:42.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:155:0)
[05:58:42.966] Timer tick, asking Buildbucket for the build status
[05:58:43.339] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:43.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:156:0) after 1m0s
[05:59:43.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:156:0)
[05:59:43.373] Timer tick, asking Buildbucket for the build status
[05:59:43.695] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:43.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:157:0) after 1m0s
[06:00:43.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:157:0)
[06:00:43.814] Timer tick, asking Buildbucket for the build status
[06:00:43.944] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:43.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:158:0) after 1m0s
[06:01:43.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:158:0)
[06:01:43.998] Timer tick, asking Buildbucket for the build status
[06:01:44.099] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:44.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:159:0) after 1m0s
[06:02:44.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:159:0)
[06:02:44.124] Timer tick, asking Buildbucket for the build status
[06:02:45.428] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:45.428] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:160:0) after 1m0s
[06:03:45.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:160:0)
[06:03:45.482] Timer tick, asking Buildbucket for the build status
[06:03:45.683] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:03:45.683] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:161:0) after 1m0s
[06:04:45.711] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:161:0)
[06:04:45.711] Timer tick, asking Buildbucket for the build status
[06:04:45.806] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:04:45.806] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:162:0) after 1m0s
[06:05:45.879] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:162:0)
[06:05:45.879] Timer tick, asking Buildbucket for the build status
[06:05:46.439] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:05:46.440] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:163:0) after 1m0s
[06:06:46.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:163:0)
[06:06:46.497] Timer tick, asking Buildbucket for the build status
[06:06:46.625] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:06:46.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:164:0) after 1m0s
[06:07:46.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:164:0)
[06:07:46.651] Timer tick, asking Buildbucket for the build status
[06:07:46.981] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:07:46.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:165:0) after 1m0s
[06:08:47.029] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:165:0)
[06:08:47.029] Timer tick, asking Buildbucket for the build status
[06:08:47.244] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:08:47.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:166:0) after 1m0s
[06:09:47.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:166:0)
[06:09:47.252] Timer tick, asking Buildbucket for the build status
[06:09:47.381] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:09:47.381] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:167:0) after 1m0s
[06:10:47.446] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:167:0)
[06:10:47.446] Timer tick, asking Buildbucket for the build status
[06:10:47.770] Build 8884979541340323072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:10:47.770] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:168:0) after 1m0s
[06:11:30.068] Received PubSub notification, asking Buildbucket for the build status
[06:11:30.301] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:47.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:168:0)
[06:11:47.835] Timer tick, asking Buildbucket for the build status
[06:11:47.984] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:47.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:170:0) after 1m0s
[06:12:48.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:170:0)
[06:12:48.013] Timer tick, asking Buildbucket for the build status
[06:12:48.188] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:48.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:171:0) after 1m0s
[06:13:48.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:171:0)
[06:13:48.223] Timer tick, asking Buildbucket for the build status
[06:13:48.326] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:48.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:172:0) after 1m0s
[06:14:48.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:172:0)
[06:14:48.353] Timer tick, asking Buildbucket for the build status
[06:14:48.479] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:48.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:173:0) after 1m0s
[06:15:48.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:173:0)
[06:15:48.502] Timer tick, asking Buildbucket for the build status
[06:15:48.637] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:48.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:174:0) after 1m0s
[06:16:48.669] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:174:0)
[06:16:48.669] Timer tick, asking Buildbucket for the build status
[06:16:50.611] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:50.611] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:175:0) after 1m0s
[06:17:50.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:175:0)
[06:17:50.645] Timer tick, asking Buildbucket for the build status
[06:17:51.077] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:51.077] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:176:0) after 1m0s
[06:18:51.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:176:0)
[06:18:51.137] Timer tick, asking Buildbucket for the build status
[06:18:53.186] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:53.186] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:177:0) after 1m0s
[06:19:53.222] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:177:0)
[06:19:53.222] Timer tick, asking Buildbucket for the build status
[06:19:53.784] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:53.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:178:0) after 1m0s
[06:20:53.846] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:178:0)
[06:20:53.846] Timer tick, asking Buildbucket for the build status
[06:20:54.446] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:54.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:179:0) after 1m0s
[06:21:54.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:179:0)
[06:21:54.470] Timer tick, asking Buildbucket for the build status
[06:21:54.783] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:54.783] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:180:0) after 1m0s
[06:22:54.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:180:0)
[06:22:54.818] Timer tick, asking Buildbucket for the build status
[06:22:55.600] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:55.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:181:0) after 1m0s
[06:23:55.822] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:181:0)
[06:23:55.822] Timer tick, asking Buildbucket for the build status
[06:23:55.987] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:55.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:182:0) after 1m0s
[06:24:56.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:182:0)
[06:24:56.137] Timer tick, asking Buildbucket for the build status
[06:24:56.300] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:56.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:183:0) after 1m0s
[06:25:56.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:183:0)
[06:25:56.417] Timer tick, asking Buildbucket for the build status
[06:25:56.625] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:56.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:184:0) after 1m0s
[06:26:56.729] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:184:0)
[06:26:56.729] Timer tick, asking Buildbucket for the build status
[06:26:58.042] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:58.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:185:0) after 1m0s
[06:27:58.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:185:0)
[06:27:58.166] Timer tick, asking Buildbucket for the build status
[06:27:58.342] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:58.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:186:0) after 1m0s
[06:28:58.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:186:0)
[06:28:58.369] Timer tick, asking Buildbucket for the build status
[06:28:58.498] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:58.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:187:0) after 1m0s
[06:29:58.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:187:0)
[06:29:58.626] Timer tick, asking Buildbucket for the build status
[06:29:58.888] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:58.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:188:0) after 1m0s
[06:30:58.955] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:188:0)
[06:30:58.955] Timer tick, asking Buildbucket for the build status
[06:30:59.087] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:59.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:189:0) after 1m0s
[06:31:59.112] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:189:0)
[06:31:59.112] Timer tick, asking Buildbucket for the build status
[06:31:59.342] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:59.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:190:0) after 1m0s
[06:32:59.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:190:0)
[06:32:59.354] Timer tick, asking Buildbucket for the build status
[06:32:59.622] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:59.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:191:0) after 1m0s
[06:33:59.655] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:191:0)
[06:33:59.655] Timer tick, asking Buildbucket for the build status
[06:33:59.773] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:59.773] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:192:0) after 1m0s
[06:34:59.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:192:0)
[06:34:59.812] Timer tick, asking Buildbucket for the build status
[06:34:59.952] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:59.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:193:0) after 1m0s
[06:35:59.997] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:193:0)
[06:35:59.997] Timer tick, asking Buildbucket for the build status
[06:36:00.150] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:00.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:194:0) after 1m0s
[06:37:00.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:194:0)
[06:37:00.210] Timer tick, asking Buildbucket for the build status
[06:37:00.363] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:00.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:195:0) after 1m0s
[06:38:00.425] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:195:0)
[06:38:00.425] Timer tick, asking Buildbucket for the build status
[06:38:00.556] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:00.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:196:0) after 1m0s
[06:39:00.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:196:0)
[06:39:00.754] Timer tick, asking Buildbucket for the build status
[06:39:00.896] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:00.896] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:197:0) after 1m0s
[06:40:00.950] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:197:0)
[06:40:00.950] Timer tick, asking Buildbucket for the build status
[06:40:01.374] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:01.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:198:0) after 1m0s
[06:41:01.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:198:0)
[06:41:01.438] Timer tick, asking Buildbucket for the build status
[06:41:01.574] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:01.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:199:0) after 1m0s
[06:42:01.647] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:199:0)
[06:42:01.647] Timer tick, asking Buildbucket for the build status
[06:42:01.801] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:01.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:200:0) after 1m0s
[06:43:01.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:200:0)
[06:43:01.926] Timer tick, asking Buildbucket for the build status
[06:43:02.034] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:02.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:201:0) after 1m0s
[06:44:02.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:201:0)
[06:44:02.168] Timer tick, asking Buildbucket for the build status
[06:44:02.490] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:02.490] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:202:0) after 1m0s
[06:45:02.633] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:202:0)
[06:45:02.633] Timer tick, asking Buildbucket for the build status
[06:45:02.775] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:02.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:203:0) after 1m0s
[06:46:02.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:203:0)
[06:46:02.905] Timer tick, asking Buildbucket for the build status
[06:46:03.012] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:03.012] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:204:0) after 1m0s
[06:47:03.002] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:204:0)
[06:47:03.002] Timer tick, asking Buildbucket for the build status
[06:47:03.134] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:03.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:205:0) after 1m0s
[06:48:03.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:205:0)
[06:48:03.196] Timer tick, asking Buildbucket for the build status
[06:48:03.368] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:03.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:206:0) after 1m0s
[06:49:03.454] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:206:0)
[06:49:03.454] Timer tick, asking Buildbucket for the build status
[06:49:03.542] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:03.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:207:0) after 1m0s
[06:50:03.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:207:0)
[06:50:03.570] Timer tick, asking Buildbucket for the build status
[06:50:03.670] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:03.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:208:0) after 1m0s
[06:51:03.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:208:0)
[06:51:03.768] Timer tick, asking Buildbucket for the build status
[06:51:03.863] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:03.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:209:0) after 1m0s
[06:52:03.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:209:0)
[06:52:03.898] Timer tick, asking Buildbucket for the build status
[06:52:04.165] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:04.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:210:0) after 1m0s
[06:53:04.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:210:0)
[06:53:04.295] Timer tick, asking Buildbucket for the build status
[06:53:04.414] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:04.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:211:0) after 1m0s
[06:54:04.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:211:0)
[06:54:04.523] Timer tick, asking Buildbucket for the build status
[06:54:04.663] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:04.663] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:212:0) after 1m0s
[06:55:04.750] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:212:0)
[06:55:04.750] Timer tick, asking Buildbucket for the build status
[06:55:05.045] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:05.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:213:0) after 1m0s
[06:56:05.135] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:213:0)
[06:56:05.135] Timer tick, asking Buildbucket for the build status
[06:56:05.205] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:05.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:214:0) after 1m0s
[06:57:05.325] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:214:0)
[06:57:05.325] Timer tick, asking Buildbucket for the build status
[06:57:05.432] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:05.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:215:0) after 1m0s
[06:58:05.473] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:215:0)
[06:58:05.473] Timer tick, asking Buildbucket for the build status
[06:58:05.577] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:05.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:216:0) after 1m0s
[06:59:05.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:216:0)
[06:59:05.665] Timer tick, asking Buildbucket for the build status
[06:59:05.962] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:05.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:217:0) after 1m0s
[07:00:06.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:217:0)
[07:00:06.243] Timer tick, asking Buildbucket for the build status
[07:00:06.594] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:06.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:218:0) after 1m0s
[07:01:07.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:218:0)
[07:01:07.495] Timer tick, asking Buildbucket for the build status
[07:01:07.886] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:07.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:219:0) after 1m0s
[07:02:08.134] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:219:0)
[07:02:08.134] Timer tick, asking Buildbucket for the build status
[07:02:08.238] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:08.238] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:220:0) after 1m0s
[07:03:08.706] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:220:0)
[07:03:08.706] Timer tick, asking Buildbucket for the build status
[07:03:08.869] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:08.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:221:0) after 1m0s
[07:04:09.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:221:0)
[07:04:09.150] Timer tick, asking Buildbucket for the build status
[07:04:09.218] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:09.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:222:0) after 1m0s
[07:05:09.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:222:0)
[07:05:09.295] Timer tick, asking Buildbucket for the build status
[07:05:09.509] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:09.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:223:0) after 1m0s
[07:06:09.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:223:0)
[07:06:09.535] Timer tick, asking Buildbucket for the build status
[07:06:09.751] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:09.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:224:0) after 1m0s
[07:07:09.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:224:0)
[07:07:09.800] Timer tick, asking Buildbucket for the build status
[07:07:09.898] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:09.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:225:0) after 1m0s
[07:08:09.954] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:225:0)
[07:08:09.954] Timer tick, asking Buildbucket for the build status
[07:08:10.353] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:10.353] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:226:0) after 1m0s
[07:09:10.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:226:0)
[07:09:10.380] Timer tick, asking Buildbucket for the build status
[07:09:10.692] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:10.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:227:0) after 1m0s
[07:10:10.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:227:0)
[07:10:10.794] Timer tick, asking Buildbucket for the build status
[07:10:10.942] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:10.942] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:228:0) after 1m0s
[07:11:10.990] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:228:0)
[07:11:10.990] Timer tick, asking Buildbucket for the build status
[07:11:11.304] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:11.304] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:229:0) after 1m0s
[07:12:11.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:229:0)
[07:12:11.342] Timer tick, asking Buildbucket for the build status
[07:12:11.488] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:11.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:230:0) after 1m0s
[07:13:11.594] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:230:0)
[07:13:11.594] Timer tick, asking Buildbucket for the build status
[07:13:11.733] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:11.733] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:231:0) after 1m0s
[07:14:11.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:231:0)
[07:14:11.764] Timer tick, asking Buildbucket for the build status
[07:14:13.646] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:13.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:232:0) after 1m0s
[07:15:13.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:232:0)
[07:15:13.704] Timer tick, asking Buildbucket for the build status
[07:15:13.830] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:233:0) after 1m0s
[07:16:13.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:233:0)
[07:16:13.857] Timer tick, asking Buildbucket for the build status
[07:16:14.052] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:14.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:234:0) after 1m0s
[07:17:14.086] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:234:0)
[07:17:14.086] Timer tick, asking Buildbucket for the build status
[07:17:14.209] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.209] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:235:0) after 1m0s
[07:18:14.324] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:235:0)
[07:18:14.324] Timer tick, asking Buildbucket for the build status
[07:18:14.402] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:14.402] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:236:0) after 1m0s
[07:19:14.510] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:236:0)
[07:19:14.510] Timer tick, asking Buildbucket for the build status
[07:19:14.641] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:14.641] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:237:0) after 1m0s
[07:20:14.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:237:0)
[07:20:14.734] Timer tick, asking Buildbucket for the build status
[07:20:15.133] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:15.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:238:0) after 1m0s
[07:21:15.191] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:238:0)
[07:21:15.191] Timer tick, asking Buildbucket for the build status
[07:21:17.286] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:17.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:239:0) after 1m0s
[07:22:17.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:239:0)
[07:22:17.295] Timer tick, asking Buildbucket for the build status
[07:22:17.424] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:17.424] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:240:0) after 1m0s
[07:23:17.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:240:0)
[07:23:17.524] Timer tick, asking Buildbucket for the build status
[07:23:17.710] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:17.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:241:0) after 1m0s
[07:24:17.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:241:0)
[07:24:17.730] Timer tick, asking Buildbucket for the build status
[07:24:17.807] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:17.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:242:0) after 1m0s
[07:25:17.869] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:242:0)
[07:25:17.869] Timer tick, asking Buildbucket for the build status
[07:25:21.916] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:21.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:243:0) after 1m0s
[07:26:21.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:243:0)
[07:26:21.989] Timer tick, asking Buildbucket for the build status
[07:26:22.340] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:22.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:244:0) after 1m0s
[07:27:22.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:244:0)
[07:27:22.344] Timer tick, asking Buildbucket for the build status
[07:27:23.647] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:23.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:245:0) after 1m0s
[07:28:23.724] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:245:0)
[07:28:23.724] Timer tick, asking Buildbucket for the build status
[07:28:23.988] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:23.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:246:0) after 1m0s
[07:29:24.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:246:0)
[07:29:24.004] Timer tick, asking Buildbucket for the build status
[07:29:24.386] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:24.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:247:0) after 1m0s
[07:30:24.421] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:247:0)
[07:30:24.421] Timer tick, asking Buildbucket for the build status
[07:30:24.613] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:24.613] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:248:0) after 1m0s
[07:31:24.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:248:0)
[07:31:24.646] Timer tick, asking Buildbucket for the build status
[07:31:24.746] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:24.746] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:249:0) after 1m0s
[07:32:24.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:249:0)
[07:32:24.796] Timer tick, asking Buildbucket for the build status
[07:32:24.922] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:24.922] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:250:0) after 1m0s
[07:33:24.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:250:0)
[07:33:24.930] Timer tick, asking Buildbucket for the build status
[07:33:25.063] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:25.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:251:0) after 1m0s
[07:34:25.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:251:0)
[07:34:25.119] Timer tick, asking Buildbucket for the build status
[07:34:25.382] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:25.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:252:0) after 1m0s
[07:35:25.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:252:0)
[07:35:25.420] Timer tick, asking Buildbucket for the build status
[07:35:25.851] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:25.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:253:0) after 1m0s
[07:36:25.884] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:253:0)
[07:36:25.884] Timer tick, asking Buildbucket for the build status
[07:36:26.030] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:26.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:254:0) after 1m0s
[07:37:26.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:254:0)
[07:37:26.078] Timer tick, asking Buildbucket for the build status
[07:37:28.122] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:28.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:255:0) after 1m0s
[07:38:28.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:255:0)
[07:38:28.230] Timer tick, asking Buildbucket for the build status
[07:38:28.640] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:28.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:256:0) after 1m0s
[07:39:28.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:256:0)
[07:39:28.700] Timer tick, asking Buildbucket for the build status
[07:39:28.890] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:28.890] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:257:0) after 1m0s
[07:40:28.943] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:257:0)
[07:40:28.943] Timer tick, asking Buildbucket for the build status
[07:40:29.150] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:29.150] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:258:0) after 1m0s
[07:41:29.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:258:0)
[07:41:29.185] Timer tick, asking Buildbucket for the build status
[07:41:29.466] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:29.466] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:259:0) after 1m0s
[07:42:29.524] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:259:0)
[07:42:29.524] Timer tick, asking Buildbucket for the build status
[07:42:29.577] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:29.577] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:260:0) after 1m0s
[07:43:29.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:260:0)
[07:43:29.648] Timer tick, asking Buildbucket for the build status
[07:43:29.955] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:29.955] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:261:0) after 1m0s
[07:44:29.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:261:0)
[07:44:29.991] Timer tick, asking Buildbucket for the build status
[07:44:31.413] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:31.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:262:0) after 1m0s
[07:45:31.485] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:262:0)
[07:45:31.485] Timer tick, asking Buildbucket for the build status
[07:45:31.854] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:31.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:263:0) after 1m0s
[07:46:31.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:263:0)
[07:46:31.913] Timer tick, asking Buildbucket for the build status
[07:46:32.257] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:32.257] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:264:0) after 1m0s
[07:47:32.293] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:264:0)
[07:47:32.293] Timer tick, asking Buildbucket for the build status
[07:47:32.802] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:32.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:265:0) after 1m0s
[07:48:32.841] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:265:0)
[07:48:32.841] Timer tick, asking Buildbucket for the build status
[07:48:33.281] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:33.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:266:0) after 1m0s
[07:49:33.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:266:0)
[07:49:33.296] Timer tick, asking Buildbucket for the build status
[07:49:33.484] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:33.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:267:0) after 1m0s
[07:50:33.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:267:0)
[07:50:33.527] Timer tick, asking Buildbucket for the build status
[07:50:33.735] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:33.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:268:0) after 1m0s
[07:51:33.769] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:268:0)
[07:51:33.769] Timer tick, asking Buildbucket for the build status
[07:51:33.869] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:33.869] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:269:0) after 1m0s
[07:52:33.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:269:0)
[07:52:33.905] Timer tick, asking Buildbucket for the build status
[07:52:34.004] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:34.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:270:0) after 1m0s
[07:53:34.059] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:270:0)
[07:53:34.059] Timer tick, asking Buildbucket for the build status
[07:53:34.143] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:34.143] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:271:0) after 1m0s
[07:54:34.230] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:271:0)
[07:54:34.230] Timer tick, asking Buildbucket for the build status
[07:54:34.400] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:34.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:272:0) after 1m0s
[07:55:34.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:272:0)
[07:55:34.458] Timer tick, asking Buildbucket for the build status
[07:55:34.646] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:34.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:273:0) after 1m0s
[07:56:34.690] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:273:0)
[07:56:34.690] Timer tick, asking Buildbucket for the build status
[07:56:35.034] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:35.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:274:0) after 1m0s
[07:57:35.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:274:0)
[07:57:35.071] Timer tick, asking Buildbucket for the build status
[07:57:35.204] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:35.204] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:275:0) after 1m0s
[07:58:35.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:275:0)
[07:58:35.237] Timer tick, asking Buildbucket for the build status
[07:58:35.310] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:35.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:276:0) after 1m0s
[07:59:35.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:276:0)
[07:59:35.326] Timer tick, asking Buildbucket for the build status
[07:59:36.043] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:36.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:277:0) after 1m0s
[08:00:36.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:277:0)
[08:00:36.076] Timer tick, asking Buildbucket for the build status
[08:00:36.202] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:36.202] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:278:0) after 1m0s
[08:01:36.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:278:0)
[08:01:36.232] Timer tick, asking Buildbucket for the build status
[08:01:36.498] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:36.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:279:0) after 1m0s
[08:02:36.531] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:279:0)
[08:02:36.531] Timer tick, asking Buildbucket for the build status
[08:02:36.698] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:36.698] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:280:0) after 1m0s
[08:03:36.779] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:280:0)
[08:03:36.779] Timer tick, asking Buildbucket for the build status
[08:03:36.861] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:36.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:281:0) after 1m0s
[08:04:36.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:281:0)
[08:04:36.960] Timer tick, asking Buildbucket for the build status
[08:04:37.169] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:37.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:282:0) after 1m0s
[08:05:37.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:282:0)
[08:05:37.200] Timer tick, asking Buildbucket for the build status
[08:05:37.450] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:37.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:283:0) after 1m0s
[08:06:37.480] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:283:0)
[08:06:37.480] Timer tick, asking Buildbucket for the build status
[08:06:37.615] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:37.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:284:0) after 1m0s
[08:07:37.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:284:0)
[08:07:37.656] Timer tick, asking Buildbucket for the build status
[08:07:38.076] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:38.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:285:0) after 1m0s
[08:08:38.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:285:0)
[08:08:38.063] Timer tick, asking Buildbucket for the build status
[08:08:38.959] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:38.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:286:0) after 1m0s
[08:09:39.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:286:0)
[08:09:39.030] Timer tick, asking Buildbucket for the build status
[08:09:40.138] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:40.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:287:0) after 1m0s
[08:10:40.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:287:0)
[08:10:40.176] Timer tick, asking Buildbucket for the build status
[08:10:40.396] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:40.396] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:288:0) after 1m0s
[08:11:40.443] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:288:0)
[08:11:40.443] Timer tick, asking Buildbucket for the build status
[08:11:40.600] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:40.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:289:0) after 1m0s
[08:12:40.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:289:0)
[08:12:40.663] Timer tick, asking Buildbucket for the build status
[08:12:40.734] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:40.734] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:290:0) after 1m0s
[08:13:40.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:290:0)
[08:13:40.733] Timer tick, asking Buildbucket for the build status
[08:13:40.937] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:40.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:291:0) after 1m0s
[08:14:40.998] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:291:0)
[08:14:40.998] Timer tick, asking Buildbucket for the build status
[08:14:42.767] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:42.767] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:292:0) after 1m0s
[08:15:42.807] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:292:0)
[08:15:42.807] Timer tick, asking Buildbucket for the build status
[08:15:42.937] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:42.937] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:293:0) after 1m0s
[08:16:42.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:293:0)
[08:16:42.994] Timer tick, asking Buildbucket for the build status
[08:16:43.115] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:43.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:294:0) after 1m0s
[08:17:43.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:294:0)
[08:17:43.147] Timer tick, asking Buildbucket for the build status
[08:17:43.212] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:43.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:295:0) after 1m0s
[08:18:43.307] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:295:0)
[08:18:43.307] Timer tick, asking Buildbucket for the build status
[08:18:43.489] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:43.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:296:0) after 1m0s
[08:19:43.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:296:0)
[08:19:43.528] Timer tick, asking Buildbucket for the build status
[08:19:45.753] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:45.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:297:0) after 1m0s
[08:20:45.797] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:297:0)
[08:20:45.797] Timer tick, asking Buildbucket for the build status
[08:20:47.379] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:47.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:298:0) after 1m0s
[08:21:47.556] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:298:0)
[08:21:47.556] Timer tick, asking Buildbucket for the build status
[08:21:47.713] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:47.713] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:299:0) after 1m0s
[08:22:47.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:299:0)
[08:22:47.963] Timer tick, asking Buildbucket for the build status
[08:22:48.027] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:48.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:300:0) after 1m0s
[08:23:48.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:300:0)
[08:23:48.085] Timer tick, asking Buildbucket for the build status
[08:23:48.223] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:48.223] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:301:0) after 1m0s
[08:24:48.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:301:0)
[08:24:48.528] Timer tick, asking Buildbucket for the build status
[08:24:48.786] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:48.786] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:302:0) after 1m0s
[08:25:48.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:302:0)
[08:25:48.875] Timer tick, asking Buildbucket for the build status
[08:25:49.093] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:49.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:303:0) after 1m0s
[08:26:49.164] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:303:0)
[08:26:49.164] Timer tick, asking Buildbucket for the build status
[08:26:49.285] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:49.285] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:304:0) after 1m0s
[08:27:49.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:304:0)
[08:27:49.319] Timer tick, asking Buildbucket for the build status
[08:27:49.583] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:49.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:305:0) after 1m0s
[08:28:49.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:305:0)
[08:28:49.658] Timer tick, asking Buildbucket for the build status
[08:28:49.875] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:49.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:306:0) after 1m0s
[08:29:49.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:306:0)
[08:29:49.869] Timer tick, asking Buildbucket for the build status
[08:29:50.163] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:50.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:307:0) after 1m0s
[08:30:50.178] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:307:0)
[08:30:50.178] Timer tick, asking Buildbucket for the build status
[08:30:51.282] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:51.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:308:0) after 1m0s
[08:31:51.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:308:0)
[08:31:51.316] Timer tick, asking Buildbucket for the build status
[08:31:51.444] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:51.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:309:0) after 1m0s
[08:32:51.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:309:0)
[08:32:51.470] Timer tick, asking Buildbucket for the build status
[08:32:51.572] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:51.572] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:310:0) after 1m0s
[08:33:51.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:310:0)
[08:33:51.672] Timer tick, asking Buildbucket for the build status
[08:33:51.787] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:51.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:311:0) after 1m0s
[08:34:51.906] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:311:0)
[08:34:51.907] Timer tick, asking Buildbucket for the build status
[08:34:52.049] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:52.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:312:0) after 1m0s
[08:35:52.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:312:0)
[08:35:52.093] Timer tick, asking Buildbucket for the build status
[08:35:52.201] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:52.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:313:0) after 1m0s
[08:36:52.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:313:0)
[08:36:52.241] Timer tick, asking Buildbucket for the build status
[08:36:52.351] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:52.351] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:314:0) after 1m0s
[08:37:52.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:314:0)
[08:37:52.341] Timer tick, asking Buildbucket for the build status
[08:37:52.760] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:52.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:315:0) after 1m0s
[08:38:52.809] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:315:0)
[08:38:52.809] Timer tick, asking Buildbucket for the build status
[08:38:53.097] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:53.097] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:316:0) after 1m0s
[08:39:53.165] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:316:0)
[08:39:53.165] Timer tick, asking Buildbucket for the build status
[08:39:53.471] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:53.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:317:0) after 1m0s
[08:40:53.494] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:317:0)
[08:40:53.494] Timer tick, asking Buildbucket for the build status
[08:40:53.706] Build 8884979541340323072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:53.706] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-x86_32:9050409603584489168:318:0) after 1m0s
[08:41:44.968] Received PubSub notification, asking Buildbucket for the build status
[08:41:45.017] Build 8884979541340323072: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:41:45.017] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1585039304207393",
    "created_by": "project:nacl",
    "created_ts": "1585020231836924",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884979541340323072",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-x86_32\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-2-1rsp\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64-tests-x86_32\", \"buildnumber\": 5147, \"got_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-2-1rsp\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4895-ce889b2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585030289187584",
    "status": "COMPLETED",
    "status_changed_ts": "1585030289522637",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-x86_32/5147",
      "builder:linux-pnacl-x86_64-tests-x86_32",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050409603584489168",
      "scheduler_job_id:nacl/linux-pnacl-x86_64-tests-x86_32",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884979541340323072/+/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:4b23630526222a10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585039304507706",
    "url": "https://ci.chromium.org/b/8884979541340323072",
    "utcnow_ts": "1585039305009194"
  }
}
[08:41:45.017] Invocation finished in 5h17m54.704323107s with status FAILED