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

• 8db20f20316f13d8886a2732099f5bb880568663 (4 years ago)

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

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

[05:33:20.984] New invocation is queued and will start shortly
[05:33:25.278] Starting the invocation (attempt 1)
[05:33:25.308] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:33:25.308] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:33:25.309] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9021501023178148016",
  "parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"8db20f20316f13d8886a2732099f5bb880568663\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8db20f20316f13d8886a2732099f5bb880568663\",\"title\":\"8db20f20316f13d8886a2732099f5bb880568663\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/8db20f20316f13d8886a2732099f5bb880568663\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"8db20f20316f13d8886a2732099f5bb880568663\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-pnacl-x86_64",
    "scheduler_invocation_id:9021501023178148016",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/8db20f20316f13d8886a2732099f5bb880568663",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8db20f20316f13d8886a2732099f5bb880568663",
    "gitiles_ref:refs/heads/master"
  ]
}
[05:33:26.004] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1612589605399955",
    "id": "8856070957887837392",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8db20f20316f13d8886a2732099f5bb880568663\", \"title\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8db20f20316f13d8886a2732099f5bb880568663\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1612589605836286",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7086",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/8db20f20316f13d8886a2732099f5bb880568663",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8db20f20316f13d8886a2732099f5bb880568663",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021501023178148016",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856070957887837392/+/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": "1612589605836400",
    "url": "https://ci.chromium.org/b/8856070957887837392",
    "utcnow_ts": "1612589605997090"
  }
}
[05:33:26.004] Task URL: https://ci.chromium.org/b/8856070957887837392
[05:33:26.004] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:2:0) after 1m0s
[05:33:33.533] Received PubSub notification, asking Buildbucket for the build status
[05:33:33.736] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:26.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:2:0)
[05:34:26.063] Timer tick, asking Buildbucket for the build status
[05:34:26.176] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:26.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:4:0) after 1m0s
[05:35:26.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:4:0)
[05:35:26.273] Timer tick, asking Buildbucket for the build status
[05:35:26.414] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:26.414] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:5:0) after 1m0s
[05:36:26.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:5:0)
[05:36:26.481] Timer tick, asking Buildbucket for the build status
[05:36:26.570] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:26.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:6:0) after 1m0s
[05:37:26.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:6:0)
[05:37:26.727] Timer tick, asking Buildbucket for the build status
[05:37:27.102] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:27.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:7:0) after 1m0s
[05:38:27.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:7:0)
[05:38:27.276] Timer tick, asking Buildbucket for the build status
[05:38:27.380] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:27.380] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:8:0) after 1m0s
[05:39:27.625] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:8:0)
[05:39:27.625] Timer tick, asking Buildbucket for the build status
[05:39:28.140] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:28.140] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:9:0) after 1m0s
[05:40:28.188] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:9:0)
[05:40:28.188] Timer tick, asking Buildbucket for the build status
[05:40:28.268] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:28.268] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:10:0) after 1m0s
[05:41:28.382] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:10:0)
[05:41:28.382] Timer tick, asking Buildbucket for the build status
[05:41:28.451] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:28.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:11:0) after 1m0s
[05:42:28.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:11:0)
[05:42:28.687] Timer tick, asking Buildbucket for the build status
[05:42:28.747] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:28.747] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:12:0) after 1m0s
[05:43:28.813] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:12:0)
[05:43:28.813] Timer tick, asking Buildbucket for the build status
[05:43:28.926] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:28.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:13:0) after 1m0s
[05:44:28.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:13:0)
[05:44:28.941] Timer tick, asking Buildbucket for the build status
[05:44:29.191] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:29.191] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:14:0) after 1m0s
[05:45:29.328] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:14:0)
[05:45:29.328] Timer tick, asking Buildbucket for the build status
[05:45:29.459] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:29.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:15:0) after 1m0s
[05:46:29.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:15:0)
[05:46:29.526] Timer tick, asking Buildbucket for the build status
[05:46:29.658] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:29.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:16:0) after 1m0s
[05:47:29.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:16:0)
[05:47:29.720] Timer tick, asking Buildbucket for the build status
[05:47:29.897] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:29.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:17:0) after 1m0s
[05:48:30.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:17:0)
[05:48:30.013] Timer tick, asking Buildbucket for the build status
[05:48:30.129] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:30.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:18:0) after 1m0s
[05:49:30.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:18:0)
[05:49:30.192] Timer tick, asking Buildbucket for the build status
[05:49:30.614] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:30.614] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:19:0) after 1m0s
[05:50:30.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:19:0)
[05:50:30.636] Timer tick, asking Buildbucket for the build status
[05:50:30.796] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:30.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:20:0) after 1m0s
[05:51:30.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:20:0)
[05:51:30.903] Timer tick, asking Buildbucket for the build status
[05:51:31.091] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:31.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:21:0) after 1m0s
[05:52:31.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:21:0)
[05:52:31.329] Timer tick, asking Buildbucket for the build status
[05:52:31.715] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:31.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:22:0) after 1m0s
[05:53:31.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:22:0)
[05:53:31.795] Timer tick, asking Buildbucket for the build status
[05:53:31.891] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:31.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:23:0) after 1m0s
[05:54:31.941] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:23:0)
[05:54:31.941] Timer tick, asking Buildbucket for the build status
[05:54:32.115] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:32.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:24:0) after 1m0s
[05:55:32.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:24:0)
[05:55:32.141] Timer tick, asking Buildbucket for the build status
[05:55:32.511] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:32.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:25:0) after 1m0s
[05:56:32.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:25:0)
[05:56:32.541] Timer tick, asking Buildbucket for the build status
[05:56:33.136] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:33.136] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:26:0) after 1m0s
[05:57:33.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:26:0)
[05:57:33.124] Timer tick, asking Buildbucket for the build status
[05:57:33.212] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:33.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:27:0) after 1m0s
[05:58:33.263] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:27:0)
[05:58:33.263] Timer tick, asking Buildbucket for the build status
[05:58:33.357] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:33.357] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:28:0) after 1m0s
[05:59:33.373] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:28:0)
[05:59:33.373] Timer tick, asking Buildbucket for the build status
[05:59:33.494] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:33.494] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:29:0) after 1m0s
[06:00:33.596] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:29:0)
[06:00:33.596] Timer tick, asking Buildbucket for the build status
[06:00:34.032] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:34.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:30:0) after 1m0s
[06:01:41.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:30:0)
[06:01:41.777] Timer tick, asking Buildbucket for the build status
[06:01:42.006] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:42.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:31:0) after 1m0s
[06:02:42.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:31:0)
[06:02:42.117] Timer tick, asking Buildbucket for the build status
[06:02:42.193] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:42.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:32:0) after 1m0s
[06:03:42.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:32:0)
[06:03:42.208] Timer tick, asking Buildbucket for the build status
[06:03:42.293] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:42.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:33:0) after 1m0s
[06:04:42.282] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:33:0)
[06:04:42.282] Timer tick, asking Buildbucket for the build status
[06:04:42.533] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:42.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:34:0) after 1m0s
[06:05:42.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:34:0)
[06:05:42.548] Timer tick, asking Buildbucket for the build status
[06:05:42.679] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:42.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:35:0) after 1m0s
[06:06:42.725] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:35:0)
[06:06:42.725] Timer tick, asking Buildbucket for the build status
[06:06:42.780] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:42.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:36:0) after 1m0s
[06:07:42.864] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:36:0)
[06:07:42.864] Timer tick, asking Buildbucket for the build status
[06:07:42.920] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:42.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:37:0) after 1m0s
[06:08:43.020] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:37:0)
[06:08:43.020] Timer tick, asking Buildbucket for the build status
[06:08:43.423] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:43.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:38:0) after 1m0s
[06:09:43.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:38:0)
[06:09:43.526] Timer tick, asking Buildbucket for the build status
[06:09:43.579] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:43.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:39:0) after 1m0s
[06:10:43.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:39:0)
[06:10:43.603] Timer tick, asking Buildbucket for the build status
[06:10:43.662] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:43.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:40:0) after 1m0s
[06:11:43.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:40:0)
[06:11:43.645] Timer tick, asking Buildbucket for the build status
[06:11:43.723] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:43.723] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:41:0) after 1m0s
[06:12:43.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:41:0)
[06:12:43.794] Timer tick, asking Buildbucket for the build status
[06:12:43.856] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:43.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:42:0) after 1m0s
[06:13:43.898] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:42:0)
[06:13:43.898] Timer tick, asking Buildbucket for the build status
[06:13:44.105] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:44.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:43:0) after 1m0s
[06:14:44.219] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:43:0)
[06:14:44.219] Timer tick, asking Buildbucket for the build status
[06:14:44.995] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:44.995] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:44:0) after 1m0s
[06:15:44.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:44:0)
[06:15:44.994] Timer tick, asking Buildbucket for the build status
[06:15:45.474] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:45.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:45:0) after 1m0s
[06:16:45.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:45:0)
[06:16:45.444] Timer tick, asking Buildbucket for the build status
[06:16:46.044] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:46.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:46:0) after 1m0s
[06:17:46.060] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:46:0)
[06:17:46.060] Timer tick, asking Buildbucket for the build status
[06:17:47.099] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:47.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:47:0) after 1m0s
[06:18:47.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:47:0)
[06:18:47.151] Timer tick, asking Buildbucket for the build status
[06:18:47.528] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:47.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:48:0) after 1m0s
[06:19:47.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:48:0)
[06:19:47.551] Timer tick, asking Buildbucket for the build status
[06:19:47.751] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:47.751] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:49:0) after 1m0s
[06:20:47.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:49:0)
[06:20:47.738] Timer tick, asking Buildbucket for the build status
[06:20:50.006] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:50.006] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:50:0) after 1m0s
[06:21:50.067] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:50:0)
[06:21:50.068] Timer tick, asking Buildbucket for the build status
[06:21:52.048] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:52.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:51:0) after 1m0s
[06:22:52.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:51:0)
[06:22:52.101] Timer tick, asking Buildbucket for the build status
[06:22:53.863] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:53.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:52:0) after 1m0s
[06:23:53.887] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:52:0)
[06:23:53.887] Timer tick, asking Buildbucket for the build status
[06:23:56.114] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:56.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:53:0) after 1m0s
[06:24:56.233] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:53:0)
[06:24:56.233] Timer tick, asking Buildbucket for the build status
[06:24:56.330] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:56.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:54:0) after 1m0s
[06:25:56.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:54:0)
[06:25:56.384] Timer tick, asking Buildbucket for the build status
[06:25:56.522] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:56.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:55:0) after 1m0s
[06:26:56.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:55:0)
[06:26:56.686] Timer tick, asking Buildbucket for the build status
[06:26:58.852] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:58.852] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:56:0) after 1m0s
[06:27:58.975] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:56:0)
[06:27:58.975] Timer tick, asking Buildbucket for the build status
[06:28:00.590] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:00.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:57:0) after 1m0s
[06:29:00.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:57:0)
[06:29:00.788] Timer tick, asking Buildbucket for the build status
[06:29:01.312] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:01.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:58:0) after 1m0s
[06:30:01.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:58:0)
[06:30:01.732] Timer tick, asking Buildbucket for the build status
[06:30:02.817] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:02.817] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:59:0) after 1m0s
[06:31:02.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:59:0)
[06:31:02.911] Timer tick, asking Buildbucket for the build status
[06:31:06.219] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:06.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:60:0) after 1m0s
[06:32:06.601] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:60:0)
[06:32:06.601] Timer tick, asking Buildbucket for the build status
[06:32:08.088] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:08.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:61:0) after 1m0s
[06:33:08.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:61:0)
[06:33:08.291] Timer tick, asking Buildbucket for the build status
[06:33:08.552] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:08.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:62:0) after 1m0s
[06:34:08.740] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:62:0)
[06:34:08.740] Timer tick, asking Buildbucket for the build status
[06:34:09.582] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:09.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:63:0) after 1m0s
[06:35:09.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:63:0)
[06:35:09.682] Timer tick, asking Buildbucket for the build status
[06:35:13.801] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:13.801] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:64:0) after 1m0s
[06:36:13.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:64:0)
[06:36:13.902] Timer tick, asking Buildbucket for the build status
[06:36:18.514] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:18.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:65:0) after 1m0s
[06:37:18.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:65:0)
[06:37:18.528] Timer tick, asking Buildbucket for the build status
[06:37:29.403] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:29.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:66:0) after 1m0s
[06:38:29.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:66:0)
[06:38:29.491] Timer tick, asking Buildbucket for the build status
[06:38:29.850] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:29.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:67:0) after 1m0s
[06:39:29.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:67:0)
[06:39:29.899] Timer tick, asking Buildbucket for the build status
[06:39:31.409] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:31.409] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:68:0) after 1m0s
[06:40:31.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:68:0)
[06:40:31.392] Timer tick, asking Buildbucket for the build status
[06:40:37.627] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:37.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:69:0) after 1m0s
[06:41:37.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:69:0)
[06:41:37.646] Timer tick, asking Buildbucket for the build status
[06:41:41.484] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:41.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:70:0) after 1m0s
[06:42:41.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:70:0)
[06:42:41.577] Timer tick, asking Buildbucket for the build status
[06:42:43.952] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:43.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:71:0) after 1m0s
[06:43:44.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:71:0)
[06:43:44.013] Timer tick, asking Buildbucket for the build status
[06:43:45.122] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:45.122] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:72:0) after 1m0s
[06:44:45.142] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:72:0)
[06:44:45.142] Timer tick, asking Buildbucket for the build status
[06:44:47.930] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:47.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:73:0) after 1m0s
[06:45:48.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:73:0)
[06:45:48.017] Timer tick, asking Buildbucket for the build status
[06:45:48.314] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:48.314] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:74:0) after 1m0s
[06:46:48.417] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:74:0)
[06:46:48.417] Timer tick, asking Buildbucket for the build status
[06:46:48.618] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:48.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:75:0) after 1m0s
[06:47:48.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:75:0)
[06:47:48.663] Timer tick, asking Buildbucket for the build status
[06:47:52.109] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:52.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:76:0) after 1m0s
[06:48:52.091] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:76:0)
[06:48:52.091] Timer tick, asking Buildbucket for the build status
[06:48:52.651] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:52.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:77:0) after 1m0s
[06:49:52.744] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:77:0)
[06:49:52.744] Timer tick, asking Buildbucket for the build status
[06:49:52.977] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:52.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:78:0) after 1m0s
[06:50:53.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:78:0)
[06:50:53.005] Timer tick, asking Buildbucket for the build status
[06:50:53.839] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:53.839] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:79:0) after 1m0s
[06:51:53.910] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:79:0)
[06:51:53.910] Timer tick, asking Buildbucket for the build status
[06:51:54.103] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:54.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:80:0) after 1m0s
[06:52:54.153] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:80:0)
[06:52:54.153] Timer tick, asking Buildbucket for the build status
[06:52:55.728] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:55.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:81:0) after 1m0s
[06:53:55.830] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:81:0)
[06:53:55.830] Timer tick, asking Buildbucket for the build status
[06:53:55.920] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:55.920] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:82:0) after 1m0s
[06:54:56.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:82:0)
[06:54:56.043] Timer tick, asking Buildbucket for the build status
[06:54:57.027] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:57.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:83:0) after 1m0s
[06:55:57.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:83:0)
[06:55:57.157] Timer tick, asking Buildbucket for the build status
[06:56:00.883] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:00.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:84:0) after 1m0s
[06:57:01.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:84:0)
[06:57:01.023] Timer tick, asking Buildbucket for the build status
[06:57:06.939] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:06.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:85:0) after 1m0s
[06:58:07.017] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:85:0)
[06:58:07.017] Timer tick, asking Buildbucket for the build status
[06:58:07.511] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:07.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:86:0) after 1m0s
[06:59:07.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:86:0)
[06:59:07.491] Timer tick, asking Buildbucket for the build status
[06:59:13.274] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:13.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:87:0) after 1m0s
[07:00:13.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:87:0)
[07:00:13.362] Timer tick, asking Buildbucket for the build status
[07:00:14.828] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:14.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:88:0) after 1m0s
[07:01:14.957] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:88:0)
[07:01:14.957] Timer tick, asking Buildbucket for the build status
[07:01:17.092] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:17.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:89:0) after 1m0s
[07:02:17.184] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:89:0)
[07:02:17.184] Timer tick, asking Buildbucket for the build status
[07:02:23.988] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:23.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:90:0) after 1m0s
[07:03:23.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:90:0)
[07:03:23.999] Timer tick, asking Buildbucket for the build status
[07:03:29.343] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:29.343] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:91:0) after 1m0s
[07:04:29.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:91:0)
[07:04:29.404] Timer tick, asking Buildbucket for the build status
[07:04:32.023] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:32.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:92:0) after 1m0s
[07:05:32.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:92:0)
[07:05:32.045] Timer tick, asking Buildbucket for the build status
[07:05:32.171] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:32.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:93:0) after 1m0s
[07:06:32.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:93:0)
[07:06:32.304] Timer tick, asking Buildbucket for the build status
[07:06:48.134] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:48.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:94:0) after 1m0s
[07:07:48.156] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:94:0)
[07:07:48.156] Timer tick, asking Buildbucket for the build status
[07:07:50.254] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:50.254] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:95:0) after 1m0s
[07:08:50.280] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:95:0)
[07:08:50.280] Timer tick, asking Buildbucket for the build status
[07:08:50.465] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:50.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:96:0) after 1m0s
[07:09:50.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:96:0)
[07:09:50.636] Timer tick, asking Buildbucket for the build status
[07:09:51.084] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:51.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:97:0) after 1m0s
[07:10:51.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:97:0)
[07:10:51.235] Timer tick, asking Buildbucket for the build status
[07:10:52.882] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:52.882] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:98:0) after 1m0s
[07:11:52.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:98:0)
[07:11:52.939] Timer tick, asking Buildbucket for the build status
[07:11:54.144] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:54.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:99:0) after 1m0s
[07:12:54.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:99:0)
[07:12:54.229] Timer tick, asking Buildbucket for the build status
[07:12:55.443] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:55.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:100:0) after 1m0s
[07:13:55.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:100:0)
[07:13:55.552] Timer tick, asking Buildbucket for the build status
[07:13:55.910] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:55.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:101:0) after 1m0s
[07:14:55.996] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:101:0)
[07:14:55.996] Timer tick, asking Buildbucket for the build status
[07:14:56.344] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:56.344] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:102:0) after 1m0s
[07:15:56.363] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:102:0)
[07:15:56.363] Timer tick, asking Buildbucket for the build status
[07:15:59.224] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:59.224] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:103:0) after 1m0s
[07:16:59.245] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:103:0)
[07:16:59.245] Timer tick, asking Buildbucket for the build status
[07:16:59.715] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:59.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:104:0) after 1m0s
[07:17:59.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:104:0)
[07:17:59.734] Timer tick, asking Buildbucket for the build status
[07:17:59.883] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:59.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:105:0) after 1m0s
[07:18:59.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:105:0)
[07:18:59.917] Timer tick, asking Buildbucket for the build status
[07:19:04.812] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:04.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:106:0) after 1m0s
[07:20:04.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:106:0)
[07:20:04.986] Timer tick, asking Buildbucket for the build status
[07:20:08.737] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:08.737] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:107:0) after 1m0s
[07:21:08.919] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:107:0)
[07:21:08.919] Timer tick, asking Buildbucket for the build status
[07:21:12.517] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:12.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:108:0) after 1m0s
[07:22:12.629] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:108:0)
[07:22:12.629] Timer tick, asking Buildbucket for the build status
[07:22:12.745] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:12.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:109:0) after 1m0s
[07:23:12.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:109:0)
[07:23:12.764] Timer tick, asking Buildbucket for the build status
[07:23:14.179] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:14.179] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:110:0) after 1m0s
[07:24:14.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:110:0)
[07:24:14.301] Timer tick, asking Buildbucket for the build status
[07:24:21.522] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:21.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:111:0) after 1m0s
[07:25:21.542] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:111:0)
[07:25:21.542] Timer tick, asking Buildbucket for the build status
[07:25:21.636] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:21.636] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:112:0) after 1m0s
[07:26:21.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:112:0)
[07:26:21.702] Timer tick, asking Buildbucket for the build status
[07:26:22.237] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:22.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:113:0) after 1m0s
[07:27:22.276] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:113:0)
[07:27:22.276] Timer tick, asking Buildbucket for the build status
[07:27:23.106] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:23.106] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:114:0) after 1m0s
[07:28:23.130] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:114:0)
[07:28:23.130] Timer tick, asking Buildbucket for the build status
[07:28:23.315] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:23.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:115:0) after 1m0s
[07:29:24.126] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:115:0)
[07:29:24.126] Timer tick, asking Buildbucket for the build status
[07:29:24.777] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:24.777] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:116:0) after 1m0s
[07:30:24.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:116:0)
[07:30:24.805] Timer tick, asking Buildbucket for the build status
[07:30:25.071] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:25.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:117:0) after 1m0s
[07:31:25.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:117:0)
[07:31:25.235] Timer tick, asking Buildbucket for the build status
[07:31:25.336] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:25.336] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:118:0) after 1m0s
[07:32:25.565] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:118:0)
[07:32:25.565] Timer tick, asking Buildbucket for the build status
[07:32:25.802] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:25.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:119:0) after 1m0s
[07:33:25.895] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:119:0)
[07:33:25.895] Timer tick, asking Buildbucket for the build status
[07:33:26.050] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:26.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:120:0) after 1m0s
[07:34:26.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:120:0)
[07:34:26.301] Timer tick, asking Buildbucket for the build status
[07:34:26.558] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:26.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:121:0) after 1m0s
[07:35:26.603] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:121:0)
[07:35:26.603] Timer tick, asking Buildbucket for the build status
[07:35:26.830] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:26.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:122:0) after 1m0s
[07:36:27.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:122:0)
[07:36:27.024] Timer tick, asking Buildbucket for the build status
[07:36:27.330] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:27.330] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:123:0) after 1m0s
[07:37:27.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:123:0)
[07:37:27.539] Timer tick, asking Buildbucket for the build status
[07:37:27.930] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:27.930] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:124:0) after 1m0s
[07:38:28.101] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:124:0)
[07:38:28.101] Timer tick, asking Buildbucket for the build status
[07:38:28.511] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:28.511] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:125:0) after 1m0s
[07:39:28.868] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:125:0)
[07:39:28.868] Timer tick, asking Buildbucket for the build status
[07:39:29.298] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:29.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:126:0) after 1m0s
[07:40:29.347] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:126:0)
[07:40:29.347] Timer tick, asking Buildbucket for the build status
[07:40:31.198] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:31.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:127:0) after 1m0s
[07:41:31.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:127:0)
[07:41:31.342] Timer tick, asking Buildbucket for the build status
[07:41:31.467] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:31.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:128:0) after 1m0s
[07:42:31.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:128:0)
[07:42:31.530] Timer tick, asking Buildbucket for the build status
[07:42:31.828] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:31.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:129:0) after 1m0s
[07:43:31.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:129:0)
[07:43:31.968] Timer tick, asking Buildbucket for the build status
[07:43:32.043] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:32.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:130:0) after 1m0s
[07:44:32.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:130:0)
[07:44:32.118] Timer tick, asking Buildbucket for the build status
[07:44:33.935] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:33.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:131:0) after 1m0s
[07:45:34.038] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:131:0)
[07:45:34.038] Timer tick, asking Buildbucket for the build status
[07:45:34.174] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:34.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:132:0) after 1m0s
[07:46:34.199] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:132:0)
[07:46:34.199] Timer tick, asking Buildbucket for the build status
[07:46:34.289] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:34.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:133:0) after 1m0s
[07:47:34.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:133:0)
[07:47:34.353] Timer tick, asking Buildbucket for the build status
[07:47:34.421] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:34.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:134:0) after 1m0s
[07:48:34.474] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:134:0)
[07:48:34.474] Timer tick, asking Buildbucket for the build status
[07:48:34.725] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:34.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:135:0) after 1m0s
[07:49:34.800] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:135:0)
[07:49:34.800] Timer tick, asking Buildbucket for the build status
[07:49:37.601] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:37.601] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:136:0) after 1m0s
[07:50:37.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:136:0)
[07:50:37.689] Timer tick, asking Buildbucket for the build status
[07:50:37.971] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:37.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:137:0) after 1m0s
[07:51:38.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:137:0)
[07:51:38.072] Timer tick, asking Buildbucket for the build status
[07:51:38.159] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:38.159] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:138:0) after 1m0s
[07:52:38.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:138:0)
[07:52:38.259] Timer tick, asking Buildbucket for the build status
[07:52:40.173] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:40.173] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:139:0) after 1m0s
[07:53:40.274] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:139:0)
[07:53:40.274] Timer tick, asking Buildbucket for the build status
[07:53:42.234] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:42.234] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:140:0) after 1m0s
[07:54:42.277] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:140:0)
[07:54:42.277] Timer tick, asking Buildbucket for the build status
[07:54:42.439] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:42.439] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:141:0) after 1m0s
[07:55:42.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:141:0)
[07:55:42.493] Timer tick, asking Buildbucket for the build status
[07:55:42.780] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:42.780] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:142:0) after 1m0s
[07:56:42.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:142:0)
[07:56:42.767] Timer tick, asking Buildbucket for the build status
[07:56:43.898] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:43.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:143:0) after 1m0s
[07:57:43.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:143:0)
[07:57:43.956] Timer tick, asking Buildbucket for the build status
[07:57:45.921] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:45.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:144:0) after 1m0s
[07:58:45.946] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:144:0)
[07:58:45.946] Timer tick, asking Buildbucket for the build status
[07:58:46.142] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:46.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:145:0) after 1m0s
[07:59:46.132] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:145:0)
[07:59:46.132] Timer tick, asking Buildbucket for the build status
[07:59:46.329] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:46.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:146:0) after 1m0s
[08:00:46.367] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:146:0)
[08:00:46.367] Timer tick, asking Buildbucket for the build status
[08:00:46.477] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:46.477] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:147:0) after 1m0s
[08:01:46.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:147:0)
[08:01:46.582] Timer tick, asking Buildbucket for the build status
[08:01:47.502] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:47.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:148:0) after 1m0s
[08:02:47.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:148:0)
[08:02:47.644] Timer tick, asking Buildbucket for the build status
[08:02:49.283] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:49.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:149:0) after 1m0s
[08:03:49.301] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:149:0)
[08:03:49.301] Timer tick, asking Buildbucket for the build status
[08:03:50.516] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:50.516] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:150:0) after 1m0s
[08:04:50.626] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:150:0)
[08:04:50.626] Timer tick, asking Buildbucket for the build status
[08:04:51.058] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:51.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:151:0) after 1m0s
[08:05:51.039] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:151:0)
[08:05:51.039] Timer tick, asking Buildbucket for the build status
[08:05:53.432] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:53.432] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:152:0) after 1m0s
[08:06:53.457] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:152:0)
[08:06:53.457] Timer tick, asking Buildbucket for the build status
[08:06:53.617] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:53.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:153:0) after 1m0s
[08:07:53.786] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:153:0)
[08:07:53.786] Timer tick, asking Buildbucket for the build status
[08:07:54.045] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:54.045] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:154:0) after 1m0s
[08:08:54.030] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:154:0)
[08:08:54.030] Timer tick, asking Buildbucket for the build status
[08:08:54.255] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:54.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:155:0) after 1m0s
[08:09:54.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:155:0)
[08:09:54.305] Timer tick, asking Buildbucket for the build status
[08:09:54.469] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:54.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:156:0) after 1m0s
[08:10:54.583] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:156:0)
[08:10:54.583] Timer tick, asking Buildbucket for the build status
[08:10:55.059] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:55.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:157:0) after 1m0s
[08:11:55.092] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:157:0)
[08:11:55.092] Timer tick, asking Buildbucket for the build status
[08:11:55.342] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:55.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:158:0) after 1m0s
[08:12:55.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:158:0)
[08:12:55.365] Timer tick, asking Buildbucket for the build status
[08:12:55.492] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:55.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:159:0) after 1m0s
[08:13:55.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:159:0)
[08:13:55.505] Timer tick, asking Buildbucket for the build status
[08:13:55.644] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:55.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:160:0) after 1m0s
[08:14:55.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:160:0)
[08:14:55.751] Timer tick, asking Buildbucket for the build status
[08:14:55.844] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:55.844] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:161:0) after 1m0s
[08:15:55.873] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:161:0)
[08:15:55.873] Timer tick, asking Buildbucket for the build status
[08:15:56.134] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:56.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:162:0) after 1m0s
[08:16:56.157] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:162:0)
[08:16:56.157] Timer tick, asking Buildbucket for the build status
[08:16:56.517] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:56.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:163:0) after 1m0s
[08:17:56.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:163:0)
[08:17:56.581] Timer tick, asking Buildbucket for the build status
[08:17:56.700] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:56.700] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:164:0) after 1m0s
[08:18:56.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:164:0)
[08:18:56.733] Timer tick, asking Buildbucket for the build status
[08:18:57.055] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:57.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:165:0) after 1m0s
[08:19:57.137] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:165:0)
[08:19:57.137] Timer tick, asking Buildbucket for the build status
[08:19:58.799] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:58.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:166:0) after 1m0s
[08:20:58.776] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:166:0)
[08:20:58.776] Timer tick, asking Buildbucket for the build status
[08:20:58.830] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:58.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:167:0) after 1m0s
[08:21:58.827] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:167:0)
[08:21:58.827] Timer tick, asking Buildbucket for the build status
[08:21:58.945] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:58.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:168:0) after 1m0s
[08:22:58.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:168:0)
[08:22:58.927] Timer tick, asking Buildbucket for the build status
[08:22:59.356] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:59.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:169:0) after 1m0s
[08:23:59.393] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:169:0)
[08:23:59.393] Timer tick, asking Buildbucket for the build status
[08:23:59.617] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:59.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:170:0) after 1m0s
[08:24:59.619] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:170:0)
[08:24:59.619] Timer tick, asking Buildbucket for the build status
[08:24:59.873] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:59.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:171:0) after 1m0s
[08:25:59.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:171:0)
[08:25:59.930] Timer tick, asking Buildbucket for the build status
[08:25:59.985] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:59.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:172:0) after 1m0s
[08:26:59.976] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:172:0)
[08:26:59.976] Timer tick, asking Buildbucket for the build status
[08:27:00.297] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:00.297] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:173:0) after 1m0s
[08:28:00.330] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:173:0)
[08:28:00.330] Timer tick, asking Buildbucket for the build status
[08:28:00.444] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:00.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:174:0) after 1m0s
[08:29:00.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:174:0)
[08:29:00.465] Timer tick, asking Buildbucket for the build status
[08:29:00.790] Build 8856070957887837392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:00.790] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9021501023178148016:175:0) after 1m0s
[08:29:36.930] Received PubSub notification, asking Buildbucket for the build status
[08:29:37.006] Build 8856070957887837392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:29:37.006] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1612600175907384",
    "created_by": "project:nacl",
    "created_ts": "1612589605399955",
    "id": "8856070957887837392",
    "parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8db20f20316f13d8886a2732099f5bb880568663\", \"title\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8db20f20316f13d8886a2732099f5bb880568663\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@8db20f20316f13d8886a2732099f5bb880568663\", \"title\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/8db20f20316f13d8886a2732099f5bb880568663\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-1-2zch\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7086, \"got_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-1-2zch\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1612589612822256",
    "status": "COMPLETED",
    "status_changed_ts": "1612600176639971",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7086",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/8db20f20316f13d8886a2732099f5bb880568663",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/8db20f20316f13d8886a2732099f5bb880568663",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9021501023178148016",
      "scheduler_job_id:nacl/linux-pnacl-x86_64",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8856070957887837392/+/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:518ea63067176c10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1612600176662152",
    "url": "https://ci.chromium.org/b/8856070957887837392",
    "utcnow_ts": "1612600176996977"
  }
}
[08:29:37.006] Invocation finished in 2h56m16.036452026s with status SUCCEEDED