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

• 9f9b2606ff17614d49a8bcd854b52568e2891e8c (4 years ago)

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

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

[03:05:46.448] New invocation is queued and will start shortly
[03:05:47.782] Starting the invocation (attempt 1)
[03:05:47.823] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:05:47.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:05:47.823] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9050048352259988128",
  "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\":\"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@9f9b2606ff17614d49a8bcd854b52568e2891e8c\",\"title\":\"9f9b2606ff17614d49a8bcd854b52568e2891e8c\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}}",
  "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:9050048352259988128",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
    "gitiles_ref:refs/heads/master"
  ]
}
[03:05:51.293] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "created_by": "project:nacl",
    "created_ts": "1585364747935422",
    "id": "8884618290027519680",
    "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\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"title\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585364751013428",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7059",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050048352259988128",
      "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/8884618290027519680/+/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": "1585364751013575",
    "url": "https://ci.chromium.org/b/8884618290027519680",
    "utcnow_ts": "1585364751241494"
  }
}
[03:05:51.293] Task URL: https://ci.chromium.org/b/8884618290027519680
[03:05:51.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:2:0) after 1m0s
[03:05:55.340] Received PubSub notification, asking Buildbucket for the build status
[03:05:55.693] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:51.352] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:2:0)
[03:06:51.352] Timer tick, asking Buildbucket for the build status
[03:06:51.774] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:51.774] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:4:0) after 1m0s
[03:07:51.762] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:4:0)
[03:07:51.762] Timer tick, asking Buildbucket for the build status
[03:07:51.867] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:51.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:5:0) after 1m0s
[03:08:51.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:5:0)
[03:08:51.901] Timer tick, asking Buildbucket for the build status
[03:08:52.196] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:52.196] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:6:0) after 1m0s
[03:09:52.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:6:0)
[03:09:52.205] Timer tick, asking Buildbucket for the build status
[03:09:52.367] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:52.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:7:0) after 1m0s
[03:10:52.424] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:7:0)
[03:10:52.425] Timer tick, asking Buildbucket for the build status
[03:10:52.678] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:52.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:8:0) after 1m0s
[03:11:52.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:8:0)
[03:11:52.703] Timer tick, asking Buildbucket for the build status
[03:11:52.830] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:52.830] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:9:0) after 1m0s
[03:12:52.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:9:0)
[03:12:52.853] Timer tick, asking Buildbucket for the build status
[03:12:53.001] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:53.001] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:10:0) after 1m0s
[03:13:53.046] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:10:0)
[03:13:53.046] Timer tick, asking Buildbucket for the build status
[03:13:53.195] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:53.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:11:0) after 1m0s
[03:14:53.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:11:0)
[03:14:53.220] Timer tick, asking Buildbucket for the build status
[03:14:53.391] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:53.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:12:0) after 1m0s
[03:15:53.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:12:0)
[03:15:53.433] Timer tick, asking Buildbucket for the build status
[03:15:53.600] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:53.600] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:13:0) after 1m0s
[03:16:53.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:13:0)
[03:16:53.648] Timer tick, asking Buildbucket for the build status
[03:16:53.787] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:53.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:14:0) after 1m0s
[03:17:53.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:14:0)
[03:17:53.863] Timer tick, asking Buildbucket for the build status
[03:17:53.982] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:53.983] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:15:0) after 1m0s
[03:18:54.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:15:0)
[03:18:54.007] Timer tick, asking Buildbucket for the build status
[03:18:54.129] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:54.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:16:0) after 1m0s
[03:19:54.149] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:16:0)
[03:19:54.150] Timer tick, asking Buildbucket for the build status
[03:19:54.410] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:54.410] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:17:0) after 1m0s
[03:20:54.525] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:17:0)
[03:20:54.525] Timer tick, asking Buildbucket for the build status
[03:20:54.667] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:54.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:18:0) after 1m0s
[03:21:54.840] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:18:0)
[03:21:54.840] Timer tick, asking Buildbucket for the build status
[03:21:54.960] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:54.960] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:19:0) after 1m0s
[03:22:54.978] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:19:0)
[03:22:54.978] Timer tick, asking Buildbucket for the build status
[03:22:55.096] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:55.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:20:0) after 1m0s
[03:23:55.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:20:0)
[03:23:55.117] Timer tick, asking Buildbucket for the build status
[03:23:55.334] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:55.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:21:0) after 1m0s
[03:24:55.341] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:21:0)
[03:24:55.341] Timer tick, asking Buildbucket for the build status
[03:24:55.721] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:55.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:22:0) after 1m0s
[03:25:55.817] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:22:0)
[03:25:55.817] Timer tick, asking Buildbucket for the build status
[03:25:55.940] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:55.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:23:0) after 1m0s
[03:26:55.989] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:23:0)
[03:26:55.989] Timer tick, asking Buildbucket for the build status
[03:26:56.134] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:56.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:24:0) after 1m0s
[03:27:56.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:24:0)
[03:27:56.231] Timer tick, asking Buildbucket for the build status
[03:27:56.352] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:56.352] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:25:0) after 1m0s
[03:28:56.436] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:25:0)
[03:28:56.436] Timer tick, asking Buildbucket for the build status
[03:28:56.566] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:56.566] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:26:0) after 1m0s
[03:29:56.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:26:0)
[03:29:56.843] Timer tick, asking Buildbucket for the build status
[03:29:57.284] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:57.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:27:0) after 1m0s
[03:30:57.882] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:27:0)
[03:30:57.882] Timer tick, asking Buildbucket for the build status
[03:30:58.041] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:58.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:28:0) after 1m0s
[03:31:58.105] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:28:0)
[03:31:58.105] Timer tick, asking Buildbucket for the build status
[03:31:58.355] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:58.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:29:0) after 1m0s
[03:32:58.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:29:0)
[03:32:58.392] Timer tick, asking Buildbucket for the build status
[03:32:58.554] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:58.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:30:0) after 1m0s
[03:33:58.567] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:30:0)
[03:33:58.567] Timer tick, asking Buildbucket for the build status
[03:33:58.676] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:58.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:31:0) after 1m0s
[03:34:58.705] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:31:0)
[03:34:58.705] Timer tick, asking Buildbucket for the build status
[03:34:58.988] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:58.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:32:0) after 1m0s
[03:35:59.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:32:0)
[03:35:59.033] Timer tick, asking Buildbucket for the build status
[03:35:59.393] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:59.393] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:33:0) after 1m0s
[03:36:59.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:33:0)
[03:36:59.432] Timer tick, asking Buildbucket for the build status
[03:36:59.701] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:59.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:34:0) after 1m0s
[03:37:59.812] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:34:0)
[03:37:59.812] Timer tick, asking Buildbucket for the build status
[03:38:00.264] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:00.264] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:35:0) after 1m0s
[03:39:00.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:35:0)
[03:39:00.502] Timer tick, asking Buildbucket for the build status
[03:39:00.759] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:00.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:36:0) after 1m0s
[03:40:00.819] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:36:0)
[03:40:00.819] Timer tick, asking Buildbucket for the build status
[03:40:00.928] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:00.928] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:37:0) after 1m0s
[03:41:01.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:37:0)
[03:41:01.034] Timer tick, asking Buildbucket for the build status
[03:41:02.329] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:02.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:38:0) after 1m0s
[03:42:02.366] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:38:0)
[03:42:02.366] Timer tick, asking Buildbucket for the build status
[03:42:03.316] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:03.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:39:0) after 1m0s
[03:43:03.458] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:39:0)
[03:43:03.458] Timer tick, asking Buildbucket for the build status
[03:43:03.711] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:03.711] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:40:0) after 1m0s
[03:44:03.735] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:40:0)
[03:44:03.735] Timer tick, asking Buildbucket for the build status
[03:44:04.138] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:04.138] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:41:0) after 1m0s
[03:45:04.172] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:41:0)
[03:45:04.172] Timer tick, asking Buildbucket for the build status
[03:45:04.345] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:04.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:42:0) after 1m0s
[03:46:04.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:42:0)
[03:46:04.433] Timer tick, asking Buildbucket for the build status
[03:46:04.615] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:04.615] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:43:0) after 1m0s
[03:47:04.741] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:43:0)
[03:47:04.741] Timer tick, asking Buildbucket for the build status
[03:47:05.099] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:05.099] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:44:0) after 1m0s
[03:48:05.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:44:0)
[03:48:05.267] Timer tick, asking Buildbucket for the build status
[03:48:05.664] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:05.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:45:0) after 1m0s
[03:49:05.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:45:0)
[03:49:05.931] Timer tick, asking Buildbucket for the build status
[03:49:06.080] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:06.080] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:46:0) after 1m0s
[03:50:06.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:46:0)
[03:50:06.420] Timer tick, asking Buildbucket for the build status
[03:50:06.596] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:06.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:47:0) after 1m0s
[03:51:06.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:47:0)
[03:51:06.689] Timer tick, asking Buildbucket for the build status
[03:51:06.800] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:06.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:48:0) after 1m0s
[03:52:06.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:48:0)
[03:52:06.930] Timer tick, asking Buildbucket for the build status
[03:52:07.397] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:07.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:49:0) after 1m0s
[03:53:07.442] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:49:0)
[03:53:07.442] Timer tick, asking Buildbucket for the build status
[03:53:08.194] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:08.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:50:0) after 1m0s
[03:54:08.364] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:50:0)
[03:54:08.364] Timer tick, asking Buildbucket for the build status
[03:54:08.932] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:08.932] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:51:0) after 1m0s
[03:55:09.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:51:0)
[03:55:09.068] Timer tick, asking Buildbucket for the build status
[03:55:09.246] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:09.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:52:0) after 1m0s
[03:56:09.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:52:0)
[03:56:09.292] Timer tick, asking Buildbucket for the build status
[03:56:09.714] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:09.714] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:53:0) after 1m0s
[03:57:09.764] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:53:0)
[03:57:09.764] Timer tick, asking Buildbucket for the build status
[03:57:09.905] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:09.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:54:0) after 1m0s
[03:58:09.983] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:54:0)
[03:58:09.983] Timer tick, asking Buildbucket for the build status
[03:58:10.283] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:10.283] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:55:0) after 1m0s
[03:59:10.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:55:0)
[03:59:10.405] Timer tick, asking Buildbucket for the build status
[03:59:10.567] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:10.567] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:56:0) after 1m0s
[04:00:10.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:56:0)
[04:00:10.605] Timer tick, asking Buildbucket for the build status
[04:00:11.158] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:11.158] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:57:0) after 1m0s
[04:01:11.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:57:0)
[04:01:11.345] Timer tick, asking Buildbucket for the build status
[04:01:11.861] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:11.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:58:0) after 1m0s
[04:02:11.927] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:58:0)
[04:02:11.927] Timer tick, asking Buildbucket for the build status
[04:02:12.031] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:12.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:59:0) after 1m0s
[04:03:12.058] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:59:0)
[04:03:12.058] Timer tick, asking Buildbucket for the build status
[04:03:12.356] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:12.356] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:60:0) after 1m0s
[04:04:12.405] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:60:0)
[04:04:12.405] Timer tick, asking Buildbucket for the build status
[04:04:12.675] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:12.675] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:61:0) after 1m0s
[04:05:12.756] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:61:0)
[04:05:12.756] Timer tick, asking Buildbucket for the build status
[04:05:12.912] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:12.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:62:0) after 1m0s
[04:06:13.015] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:62:0)
[04:06:13.015] Timer tick, asking Buildbucket for the build status
[04:06:13.171] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:13.171] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:63:0) after 1m0s
[04:07:13.250] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:63:0)
[04:07:13.250] Timer tick, asking Buildbucket for the build status
[04:07:13.404] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:13.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:64:0) after 1m0s
[04:08:13.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:64:0)
[04:08:13.568] Timer tick, asking Buildbucket for the build status
[04:08:14.481] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:14.481] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:65:0) after 1m0s
[04:09:14.639] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:65:0)
[04:09:14.639] Timer tick, asking Buildbucket for the build status
[04:09:14.749] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:14.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:66:0) after 1m0s
[04:10:14.757] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:66:0)
[04:10:14.757] Timer tick, asking Buildbucket for the build status
[04:10:14.985] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:14.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:67:0) after 1m0s
[04:11:15.231] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:67:0)
[04:11:15.231] Timer tick, asking Buildbucket for the build status
[04:11:15.407] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:15.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:68:0) after 1m0s
[04:12:15.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:68:0)
[04:12:15.544] Timer tick, asking Buildbucket for the build status
[04:12:15.676] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:15.676] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:69:0) after 1m0s
[04:13:15.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:69:0)
[04:13:15.900] Timer tick, asking Buildbucket for the build status
[04:13:16.046] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:16.046] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:70:0) after 1m0s
[04:14:16.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:70:0)
[04:14:16.120] Timer tick, asking Buildbucket for the build status
[04:14:16.362] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:16.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:71:0) after 1m0s
[04:15:16.385] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:71:0)
[04:15:16.385] Timer tick, asking Buildbucket for the build status
[04:15:16.533] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:16.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:72:0) after 1m0s
[04:16:16.608] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:72:0)
[04:16:16.608] Timer tick, asking Buildbucket for the build status
[04:16:17.032] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:17.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:73:0) after 1m0s
[04:17:17.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:73:0)
[04:17:17.147] Timer tick, asking Buildbucket for the build status
[04:17:17.392] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:17.392] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:74:0) after 1m0s
[04:18:17.381] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:74:0)
[04:18:17.381] Timer tick, asking Buildbucket for the build status
[04:18:17.783] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:17.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:75:0) after 1m0s
[04:19:17.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:75:0)
[04:19:17.799] Timer tick, asking Buildbucket for the build status
[04:19:17.962] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:17.962] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:76:0) after 1m0s
[04:20:18.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:76:0)
[04:20:18.335] Timer tick, asking Buildbucket for the build status
[04:20:19.542] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:19.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:77:0) after 1m0s
[04:21:19.539] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:77:0)
[04:21:19.539] Timer tick, asking Buildbucket for the build status
[04:21:19.692] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:19.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:78:0) after 1m0s
[04:22:19.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:78:0)
[04:22:19.747] Timer tick, asking Buildbucket for the build status
[04:22:19.853] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:19.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:79:0) after 1m0s
[04:23:20.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:79:0)
[04:23:20.007] Timer tick, asking Buildbucket for the build status
[04:23:20.634] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:20.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:80:0) after 1m0s
[04:24:20.720] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:80:0)
[04:24:20.720] Timer tick, asking Buildbucket for the build status
[04:24:21.092] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:21.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:81:0) after 1m0s
[04:25:21.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:81:0)
[04:25:21.084] Timer tick, asking Buildbucket for the build status
[04:25:21.290] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:21.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:82:0) after 1m0s
[04:26:21.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:82:0)
[04:26:21.334] Timer tick, asking Buildbucket for the build status
[04:26:21.476] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:21.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:83:0) after 1m0s
[04:27:21.716] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:83:0)
[04:27:21.716] Timer tick, asking Buildbucket for the build status
[04:27:21.931] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:21.931] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:84:0) after 1m0s
[04:28:22.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:84:0)
[04:28:22.034] Timer tick, asking Buildbucket for the build status
[04:28:22.187] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:22.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:85:0) after 1m0s
[04:29:22.223] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:85:0)
[04:29:22.223] Timer tick, asking Buildbucket for the build status
[04:29:22.359] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:22.359] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:86:0) after 1m0s
[04:30:22.391] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:86:0)
[04:30:22.391] Timer tick, asking Buildbucket for the build status
[04:30:22.528] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:22.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:87:0) after 1m0s
[04:31:22.636] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:87:0)
[04:31:22.636] Timer tick, asking Buildbucket for the build status
[04:31:22.742] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:22.742] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:88:0) after 1m0s
[04:32:22.805] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:88:0)
[04:32:22.805] Timer tick, asking Buildbucket for the build status
[04:32:23.051] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:23.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:89:0) after 1m0s
[04:33:23.120] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:89:0)
[04:33:23.120] Timer tick, asking Buildbucket for the build status
[04:33:23.556] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:23.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:90:0) after 1m0s
[04:34:23.602] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:90:0)
[04:34:23.602] Timer tick, asking Buildbucket for the build status
[04:34:23.796] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:23.796] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:91:0) after 1m0s
[04:35:23.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:91:0)
[04:35:23.863] Timer tick, asking Buildbucket for the build status
[04:35:23.999] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:23.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:92:0) after 1m0s
[04:36:24.186] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:92:0)
[04:36:24.186] Timer tick, asking Buildbucket for the build status
[04:36:24.391] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:24.391] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:93:0) after 1m0s
[04:37:24.433] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:93:0)
[04:37:24.433] Timer tick, asking Buildbucket for the build status
[04:37:24.585] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:24.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:94:0) after 1m0s
[04:38:24.745] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:94:0)
[04:38:24.745] Timer tick, asking Buildbucket for the build status
[04:38:26.521] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:26.521] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:95:0) after 1m0s
[04:39:26.616] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:95:0)
[04:39:26.616] Timer tick, asking Buildbucket for the build status
[04:39:26.794] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:26.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:96:0) after 1m0s
[04:40:26.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:96:0)
[04:40:26.858] Timer tick, asking Buildbucket for the build status
[04:40:27.134] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:27.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:97:0) after 1m0s
[04:41:27.243] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:97:0)
[04:41:27.243] Timer tick, asking Buildbucket for the build status
[04:41:27.451] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:27.451] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:98:0) after 1m0s
[04:42:27.493] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:98:0)
[04:42:27.493] Timer tick, asking Buildbucket for the build status
[04:42:27.789] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:27.789] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:99:0) after 1m0s
[04:43:27.820] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:99:0)
[04:43:27.820] Timer tick, asking Buildbucket for the build status
[04:43:28.068] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:28.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:100:0) after 1m0s
[04:44:28.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:100:0)
[04:44:28.133] Timer tick, asking Buildbucket for the build status
[04:44:28.269] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:28.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:101:0) after 1m0s
[04:45:29.061] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:101:0)
[04:45:29.061] Timer tick, asking Buildbucket for the build status
[04:45:29.227] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:29.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:102:0) after 1m0s
[04:46:29.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:102:0)
[04:46:29.550] Timer tick, asking Buildbucket for the build status
[04:46:29.752] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:29.752] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:103:0) after 1m0s
[04:47:29.837] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:103:0)
[04:47:29.837] Timer tick, asking Buildbucket for the build status
[04:47:29.987] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:29.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:104:0) after 1m0s
[04:48:30.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:104:0)
[04:48:30.133] Timer tick, asking Buildbucket for the build status
[04:48:32.646] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:32.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:105:0) after 1m0s
[04:49:32.765] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:105:0)
[04:49:32.765] Timer tick, asking Buildbucket for the build status
[04:49:32.921] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:32.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:106:0) after 1m0s
[04:50:33.007] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:106:0)
[04:50:33.007] Timer tick, asking Buildbucket for the build status
[04:50:33.178] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:33.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:107:0) after 1m0s
[04:51:33.235] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:107:0)
[04:51:33.235] Timer tick, asking Buildbucket for the build status
[04:51:33.369] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:33.369] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:108:0) after 1m0s
[04:52:33.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:108:0)
[04:52:33.502] Timer tick, asking Buildbucket for the build status
[04:52:34.000] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:34.000] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:109:0) after 1m0s
[04:53:34.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:109:0)
[04:53:34.309] Timer tick, asking Buildbucket for the build status
[04:53:34.784] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:34.784] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:110:0) after 1m0s
[04:54:34.818] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:110:0)
[04:54:34.818] Timer tick, asking Buildbucket for the build status
[04:54:35.007] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:35.007] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:111:0) after 1m0s
[04:55:35.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:111:0)
[04:55:35.078] Timer tick, asking Buildbucket for the build status
[04:55:35.404] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:35.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:112:0) after 1m0s
[04:56:35.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:112:0)
[04:56:35.428] Timer tick, asking Buildbucket for the build status
[04:56:35.861] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:35.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:113:0) after 1m0s
[04:57:35.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:113:0)
[04:57:35.940] Timer tick, asking Buildbucket for the build status
[04:57:36.084] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:36.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:114:0) after 1m0s
[04:58:36.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:114:0)
[04:58:36.116] Timer tick, asking Buildbucket for the build status
[04:58:36.323] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:36.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:115:0) after 1m0s
[04:59:36.308] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:115:0)
[04:59:36.308] Timer tick, asking Buildbucket for the build status
[04:59:36.507] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:36.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:116:0) after 1m0s
[05:00:36.549] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:116:0)
[05:00:36.549] Timer tick, asking Buildbucket for the build status
[05:00:39.819] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:39.819] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:117:0) after 1m0s
[05:01:39.872] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:117:0)
[05:01:39.872] Timer tick, asking Buildbucket for the build status
[05:01:40.314] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:40.315] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:118:0) after 1m0s
[05:02:40.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:118:0)
[05:02:40.354] Timer tick, asking Buildbucket for the build status
[05:02:40.546] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:40.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:119:0) after 1m0s
[05:03:40.643] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:119:0)
[05:03:40.643] Timer tick, asking Buildbucket for the build status
[05:03:41.310] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:41.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:120:0) after 1m0s
[05:04:41.383] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:120:0)
[05:04:41.383] Timer tick, asking Buildbucket for the build status
[05:04:41.578] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:41.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:121:0) after 1m0s
[05:05:41.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:121:0)
[05:05:41.607] Timer tick, asking Buildbucket for the build status
[05:05:41.905] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:41.905] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:122:0) after 1m0s
[05:06:41.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:122:0)
[05:06:41.974] Timer tick, asking Buildbucket for the build status
[05:06:42.089] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:42.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:123:0) after 1m0s
[05:07:42.160] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:123:0)
[05:07:42.160] Timer tick, asking Buildbucket for the build status
[05:07:42.322] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:42.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:124:0) after 1m0s
[05:08:42.568] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:124:0)
[05:08:42.568] Timer tick, asking Buildbucket for the build status
[05:08:42.877] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:42.877] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:125:0) after 1m0s
[05:09:42.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:125:0)
[05:09:42.977] Timer tick, asking Buildbucket for the build status
[05:09:43.129] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:43.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:126:0) after 1m0s
[05:10:43.212] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:126:0)
[05:10:43.212] Timer tick, asking Buildbucket for the build status
[05:10:43.340] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:43.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:127:0) after 1m0s
[05:11:43.398] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:127:0)
[05:11:43.398] Timer tick, asking Buildbucket for the build status
[05:11:43.589] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:43.589] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:128:0) after 1m0s
[05:12:43.617] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:128:0)
[05:12:43.617] Timer tick, asking Buildbucket for the build status
[05:12:43.913] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:43.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:129:0) after 1m0s
[05:13:43.944] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:129:0)
[05:13:43.944] Timer tick, asking Buildbucket for the build status
[05:13:46.415] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:46.415] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:130:0) after 1m0s
[05:14:46.496] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:130:0)
[05:14:46.496] Timer tick, asking Buildbucket for the build status
[05:14:46.645] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:46.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:131:0) after 1m0s
[05:15:46.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:131:0)
[05:15:46.747] Timer tick, asking Buildbucket for the build status
[05:15:46.918] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:46.918] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:132:0) after 1m0s
[05:16:46.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:132:0)
[05:16:46.974] Timer tick, asking Buildbucket for the build status
[05:16:47.212] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:47.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:133:0) after 1m0s
[05:17:47.292] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:133:0)
[05:17:47.292] Timer tick, asking Buildbucket for the build status
[05:17:47.426] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:47.426] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:134:0) after 1m0s
[05:18:47.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:134:0)
[05:18:47.499] Timer tick, asking Buildbucket for the build status
[05:18:48.075] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:48.075] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:135:0) after 1m0s
[05:19:48.579] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:135:0)
[05:19:48.579] Timer tick, asking Buildbucket for the build status
[05:19:48.692] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:48.692] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:136:0) after 1m0s
[05:20:49.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:136:0)
[05:20:49.192] Timer tick, asking Buildbucket for the build status
[05:20:49.431] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:49.431] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:137:0) after 1m0s
[05:21:49.646] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:137:0)
[05:21:49.646] Timer tick, asking Buildbucket for the build status
[05:21:49.762] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:49.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:138:0) after 1m0s
[05:22:49.801] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:138:0)
[05:22:49.801] Timer tick, asking Buildbucket for the build status
[05:22:49.993] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:49.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:139:0) after 1m0s
[05:23:50.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:139:0)
[05:23:50.064] Timer tick, asking Buildbucket for the build status
[05:23:50.545] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:50.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:140:0) after 1m0s
[05:24:50.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:140:0)
[05:24:50.915] Timer tick, asking Buildbucket for the build status
[05:24:51.092] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:51.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:141:0) after 1m0s
[05:25:51.150] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:141:0)
[05:25:51.150] Timer tick, asking Buildbucket for the build status
[05:25:51.342] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:51.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:142:0) after 1m0s
[05:26:51.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:142:0)
[05:26:51.463] Timer tick, asking Buildbucket for the build status
[05:26:51.679] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:51.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:143:0) after 1m0s
[05:27:51.709] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:143:0)
[05:27:51.709] Timer tick, asking Buildbucket for the build status
[05:27:52.473] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:52.473] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:144:0) after 1m0s
[05:28:52.536] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:144:0)
[05:28:52.536] Timer tick, asking Buildbucket for the build status
[05:28:52.719] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:52.719] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:145:0) after 1m0s
[05:29:52.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:145:0)
[05:29:52.834] Timer tick, asking Buildbucket for the build status
[05:29:52.987] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:52.987] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:146:0) after 1m0s
[05:30:53.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:146:0)
[05:30:53.090] Timer tick, asking Buildbucket for the build status
[05:30:53.227] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:53.227] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:147:0) after 1m0s
[05:31:53.408] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:147:0)
[05:31:53.408] Timer tick, asking Buildbucket for the build status
[05:31:53.775] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:53.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:148:0) after 1m0s
[05:32:53.863] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:148:0)
[05:32:53.863] Timer tick, asking Buildbucket for the build status
[05:32:53.996] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:53.996] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:149:0) after 1m0s
[05:33:54.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:149:0)
[05:33:54.114] Timer tick, asking Buildbucket for the build status
[05:33:54.277] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:54.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:150:0) after 1m0s
[05:34:54.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:150:0)
[05:34:54.463] Timer tick, asking Buildbucket for the build status
[05:34:54.603] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:54.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:151:0) after 1m0s
[05:35:54.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:151:0)
[05:35:54.814] Timer tick, asking Buildbucket for the build status
[05:35:54.948] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:54.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:152:0) after 1m0s
[05:36:55.054] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:152:0)
[05:36:55.054] Timer tick, asking Buildbucket for the build status
[05:36:55.608] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:55.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:153:0) after 1m0s
[05:37:55.672] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:153:0)
[05:37:55.672] Timer tick, asking Buildbucket for the build status
[05:37:55.812] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:55.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:154:0) after 1m0s
[05:38:55.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:154:0)
[05:38:55.842] Timer tick, asking Buildbucket for the build status
[05:38:56.263] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:56.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:155:0) after 1m0s
[05:39:56.342] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:155:0)
[05:39:56.342] Timer tick, asking Buildbucket for the build status
[05:39:56.474] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:56.474] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:156:0) after 1m0s
[05:40:56.597] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:156:0)
[05:40:56.597] Timer tick, asking Buildbucket for the build status
[05:40:56.840] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:56.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:157:0) after 1m0s
[05:41:56.987] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:157:0)
[05:41:56.987] Timer tick, asking Buildbucket for the build status
[05:41:57.267] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:57.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:158:0) after 1m0s
[05:42:57.375] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:158:0)
[05:42:57.375] Timer tick, asking Buildbucket for the build status
[05:42:57.760] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:57.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:159:0) after 1m0s
[05:43:57.843] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:159:0)
[05:43:57.843] Timer tick, asking Buildbucket for the build status
[05:43:57.977] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:57.977] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:160:0) after 1m0s
[05:44:58.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:160:0)
[05:44:58.003] Timer tick, asking Buildbucket for the build status
[05:44:58.147] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:58.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:161:0) after 1m0s
[05:45:58.207] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:161:0)
[05:45:58.207] Timer tick, asking Buildbucket for the build status
[05:45:58.382] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:58.382] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:162:0) after 1m0s
[05:46:58.566] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:162:0)
[05:46:58.566] Timer tick, asking Buildbucket for the build status
[05:46:58.851] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:58.851] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:163:0) after 1m0s
[05:47:58.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:163:0)
[05:47:58.911] Timer tick, asking Buildbucket for the build status
[05:47:59.032] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:59.032] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:164:0) after 1m0s
[05:48:59.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:164:0)
[05:48:59.133] Timer tick, asking Buildbucket for the build status
[05:48:59.994] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:59.994] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:165:0) after 1m0s
[05:50:00.041] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:165:0)
[05:50:00.041] Timer tick, asking Buildbucket for the build status
[05:50:00.215] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:00.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:166:0) after 1m0s
[05:51:00.311] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:166:0)
[05:51:00.311] Timer tick, asking Buildbucket for the build status
[05:51:00.705] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:00.705] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:167:0) after 1m0s
[05:52:00.691] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:167:0)
[05:52:00.691] Timer tick, asking Buildbucket for the build status
[05:52:00.963] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:00.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:168:0) after 1m0s
[05:53:01.071] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:168:0)
[05:53:01.071] Timer tick, asking Buildbucket for the build status
[05:53:01.465] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:01.465] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:169:0) after 1m0s
[05:54:01.497] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:169:0)
[05:54:01.497] Timer tick, asking Buildbucket for the build status
[05:54:01.867] Build 8884618290027519680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:01.867] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9050048352259988128:170:0) after 1m0s
[05:54:18.790] Received PubSub notification, asking Buildbucket for the build status
[05:54:18.868] Build 8884618290027519680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:54:18.868] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary": true,
    "canary_preference": "CANARY",
    "completed_ts": "1585374856984962",
    "created_by": "project:nacl",
    "created_ts": "1585364747935422",
    "id": "8884618290027519680",
    "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\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"title\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}}",
    "project": "nacl",
    "result": "SUCCESS",
    "result_details_json": "{\"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\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"title\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-a4pw\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7059, \"got_revision\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"9f9b2606ff17614d49a8bcd854b52568e2891e8c\", \"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-0-a4pw\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"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\": [\"4901-91f024a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585364754247404",
    "status": "COMPLETED",
    "status_changed_ts": "1585374858008950",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7059",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/9f9b2606ff17614d49a8bcd854b52568e2891e8c",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050048352259988128",
      "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/8884618290027519680/+/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:4b37ebdcd2b32910",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585374858098390",
    "url": "https://ci.chromium.org/b/8884618290027519680",
    "utcnow_ts": "1585374858860764"
  }
}
[05:54:18.868] Invocation finished in 2h48m32.446230886s with status SUCCEEDED