Status: SUCCEEDED
Triggered by: -
Duration: 2 hours
Actions:

Definition (rev 1c82d86159c2071b02a7ddf08ffd2ae0bff92186)

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

Incoming triggers

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[00:56:42.726] New invocation is queued and will start shortly
[00:56:44.583] Starting the invocation (attempt 1)
[00:56:44.628] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:56:44.628] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:56:44.628] Buildbucket request:
{
  "bucket": "luci.nacl.toolchain",
  "client_operation_id": "9044348863246954144",
  "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\":\"bbb0247b832619c6c1f43da4e1c9182519824e2a\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@bbb0247b832619c6c1f43da4e1c9182519824e2a\",\"title\":\"bbb0247b832619c6c1f43da4e1c9182519824e2a\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/bbb0247b832619c6c1f43da4e1c9182519824e2a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}}",
  "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:9044348863246954144",
    "scheduler_job_id:nacl/linux-pnacl-x86_64",
    "user_agent:luci-scheduler",
    "buildset:commit/git/bbb0247b832619c6c1f43da4e1c9182519824e2a",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/bbb0247b832619c6c1f43da4e1c9182519824e2a",
    "gitiles_ref:refs/heads/master"
  ]
}
[00:56:47.453] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1590800204842210",
    "id": "8878918800365778528",
    "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\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"title\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/bbb0247b832619c6c1f43da4e1c9182519824e2a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1590800206922110",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7070",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/bbb0247b832619c6c1f43da4e1c9182519824e2a",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/bbb0247b832619c6c1f43da4e1c9182519824e2a",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9044348863246954144",
      "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/8878918800365778528/+/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": "1590800206922371",
    "url": "https://ci.chromium.org/b/8878918800365778528",
    "utcnow_ts": "1590800207426074"
  }
}
[00:56:47.453] Task URL: https://ci.chromium.org/b/8878918800365778528
[00:56:47.454] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:2:0) after 1m0s
[00:57:47.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:2:0)
[00:57:47.499] Timer tick, asking Buildbucket for the build status
[00:57:47.657] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:47.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:3:0) after 1m0s
[00:58:47.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:3:0)
[00:58:47.686] Timer tick, asking Buildbucket for the build status
[00:58:47.837] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:47.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:4:0) after 1m0s
[00:59:47.867] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:4:0)
[00:59:47.867] Timer tick, asking Buildbucket for the build status
[00:59:48.027] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:48.027] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:5:0) after 1m0s
[01:00:48.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:5:0)
[01:00:48.047] Timer tick, asking Buildbucket for the build status
[01:00:48.475] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:48.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:6:0) after 1m0s
[01:01:48.522] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:6:0)
[01:01:48.522] Timer tick, asking Buildbucket for the build status
[01:01:49.016] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:49.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:7:0) after 1m0s
[01:02:49.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:7:0)
[01:02:49.084] Timer tick, asking Buildbucket for the build status
[01:02:49.293] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:49.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:8:0) after 1m0s
[01:03:49.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:8:0)
[01:03:49.316] Timer tick, asking Buildbucket for the build status
[01:03:49.552] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:49.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:9:0) after 1m0s
[01:04:49.752] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:9:0)
[01:04:49.752] Timer tick, asking Buildbucket for the build status
[01:04:50.142] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:50.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:10:0) after 1m0s
[01:05:50.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:10:0)
[01:05:50.205] Timer tick, asking Buildbucket for the build status
[01:05:51.282] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:51.282] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:11:0) after 1m0s
[01:06:51.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:11:0)
[01:06:51.502] Timer tick, asking Buildbucket for the build status
[01:06:51.672] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:51.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:12:0) after 1m0s
[01:07:51.811] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:12:0)
[01:07:51.811] Timer tick, asking Buildbucket for the build status
[01:07:52.550] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:52.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:13:0) after 1m0s
[01:08:52.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:13:0)
[01:08:52.580] Timer tick, asking Buildbucket for the build status
[01:08:52.701] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:52.701] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:14:0) after 1m0s
[01:09:52.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:14:0)
[01:09:52.784] Timer tick, asking Buildbucket for the build status
[01:09:53.069] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:53.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:15:0) after 1m0s
[01:10:53.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:15:0)
[01:10:53.145] Timer tick, asking Buildbucket for the build status
[01:10:53.401] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:53.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:16:0) after 1m0s
[01:11:53.478] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:16:0)
[01:11:53.478] Timer tick, asking Buildbucket for the build status
[01:11:53.905] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:53.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:17:0) after 1m0s
[01:12:54.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:17:0)
[01:12:54.106] Timer tick, asking Buildbucket for the build status
[01:12:55.246] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:55.246] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:18:0) after 1m0s
[01:13:55.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:18:0)
[01:13:55.561] Timer tick, asking Buildbucket for the build status
[01:13:55.695] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:55.695] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:19:0) after 1m0s
[01:14:55.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:19:0)
[01:14:55.842] Timer tick, asking Buildbucket for the build status
[01:14:56.318] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:56.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:20:0) after 1m0s
[01:15:56.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:20:0)
[01:15:56.463] Timer tick, asking Buildbucket for the build status
[01:15:56.693] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:56.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:21:0) after 1m0s
[01:16:56.746] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:21:0)
[01:16:56.746] Timer tick, asking Buildbucket for the build status
[01:16:56.871] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:56.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:22:0) after 1m0s
[01:17:56.948] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:22:0)
[01:17:56.948] Timer tick, asking Buildbucket for the build status
[01:17:57.063] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:57.063] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:23:0) after 1m0s
[01:18:57.269] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:23:0)
[01:18:57.269] Timer tick, asking Buildbucket for the build status
[01:18:57.364] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:57.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:24:0) after 1m0s
[01:19:57.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:24:0)
[01:19:57.379] Timer tick, asking Buildbucket for the build status
[01:19:57.640] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:57.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:25:0) after 1m0s
[01:20:57.726] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:25:0)
[01:20:57.726] Timer tick, asking Buildbucket for the build status
[01:20:58.522] Build 8878918800365778528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:58.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:26:0) after 1m0s
[01:20:59.110] Received PubSub notification, asking Buildbucket for the build status
[01:21:00.134] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:58.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:26:0)
[01:21:58.575] Timer tick, asking Buildbucket for the build status
[01:21:58.926] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:58.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:28:0) after 1m0s
[01:22:58.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:28:0)
[01:22:58.985] Timer tick, asking Buildbucket for the build status
[01:22:59.478] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:59.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:29:0) after 1m0s
[01:23:59.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:29:0)
[01:23:59.541] Timer tick, asking Buildbucket for the build status
[01:23:59.729] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:59.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:30:0) after 1m0s
[01:24:59.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:30:0)
[01:24:59.749] Timer tick, asking Buildbucket for the build status
[01:25:00.332] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:00.332] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:31:0) after 1m0s
[01:26:02.043] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:31:0)
[01:26:02.043] Timer tick, asking Buildbucket for the build status
[01:26:02.220] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:02.220] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:32:0) after 1m0s
[01:27:02.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:32:0)
[01:27:02.738] Timer tick, asking Buildbucket for the build status
[01:27:02.935] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:02.935] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:33:0) after 1m0s
[01:28:02.960] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:33:0)
[01:28:02.960] Timer tick, asking Buildbucket for the build status
[01:28:03.168] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:03.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:34:0) after 1m0s
[01:29:03.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:34:0)
[01:29:03.503] Timer tick, asking Buildbucket for the build status
[01:29:03.618] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:03.618] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:35:0) after 1m0s
[01:30:03.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:35:0)
[01:30:03.645] Timer tick, asking Buildbucket for the build status
[01:30:04.464] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:04.464] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:36:0) after 1m0s
[01:31:04.548] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:36:0)
[01:31:04.548] Timer tick, asking Buildbucket for the build status
[01:31:05.090] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:05.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:37:0) after 1m0s
[01:32:05.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:37:0)
[01:32:05.099] Timer tick, asking Buildbucket for the build status
[01:32:05.287] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:05.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:38:0) after 1m0s
[01:33:05.287] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:38:0)
[01:33:05.287] Timer tick, asking Buildbucket for the build status
[01:33:05.530] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:05.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:39:0) after 1m0s
[01:34:05.682] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:39:0)
[01:34:05.682] Timer tick, asking Buildbucket for the build status
[01:34:05.847] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:05.847] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:40:0) after 1m0s
[01:35:05.886] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:40:0)
[01:35:05.886] Timer tick, asking Buildbucket for the build status
[01:35:06.095] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:06.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:41:0) after 1m0s
[01:36:06.305] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:41:0)
[01:36:06.305] Timer tick, asking Buildbucket for the build status
[01:36:06.417] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:06.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:42:0) after 1m0s
[01:37:06.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:42:0)
[01:37:06.438] Timer tick, asking Buildbucket for the build status
[01:37:06.578] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:06.578] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:43:0) after 1m0s
[01:38:06.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:43:0)
[01:38:06.605] Timer tick, asking Buildbucket for the build status
[01:38:07.177] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:07.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:44:0) after 1m0s
[01:39:07.195] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:44:0)
[01:39:07.195] Timer tick, asking Buildbucket for the build status
[01:39:07.792] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:07.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:45:0) after 1m0s
[01:40:07.900] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:45:0)
[01:40:07.900] Timer tick, asking Buildbucket for the build status
[01:40:08.583] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:08.583] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:46:0) after 1m0s
[01:41:08.567] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:46:0)
[01:41:08.567] Timer tick, asking Buildbucket for the build status
[01:41:09.292] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:09.292] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:47:0) after 1m0s
[01:42:09.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:47:0)
[01:42:09.335] Timer tick, asking Buildbucket for the build status
[01:42:09.764] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:09.764] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:48:0) after 1m0s
[01:43:09.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:48:0)
[01:43:09.852] Timer tick, asking Buildbucket for the build status
[01:43:10.071] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:10.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:49:0) after 1m0s
[01:44:10.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:49:0)
[01:44:10.152] Timer tick, asking Buildbucket for the build status
[01:44:10.479] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:10.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:50:0) after 1m0s
[01:45:10.561] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:50:0)
[01:45:10.561] Timer tick, asking Buildbucket for the build status
[01:45:11.914] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:11.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:51:0) after 1m0s
[01:46:12.028] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:51:0)
[01:46:12.028] Timer tick, asking Buildbucket for the build status
[01:46:13.475] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:13.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:52:0) after 1m0s
[01:47:13.495] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:52:0)
[01:47:13.495] Timer tick, asking Buildbucket for the build status
[01:47:14.284] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:14.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:53:0) after 1m0s
[01:48:14.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:53:0)
[01:48:14.304] Timer tick, asking Buildbucket for the build status
[01:48:14.556] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:14.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:54:0) after 1m0s
[01:49:14.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:54:0)
[01:49:14.588] Timer tick, asking Buildbucket for the build status
[01:49:15.118] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:15.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:55:0) after 1m0s
[01:50:15.139] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:55:0)
[01:50:15.139] Timer tick, asking Buildbucket for the build status
[01:50:16.468] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:16.468] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:56:0) after 1m0s
[01:51:16.490] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:56:0)
[01:51:16.490] Timer tick, asking Buildbucket for the build status
[01:51:17.016] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:17.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:57:0) after 1m0s
[01:52:17.237] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:57:0)
[01:52:17.237] Timer tick, asking Buildbucket for the build status
[01:52:17.891] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:17.891] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:58:0) after 1m0s
[01:53:17.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:58:0)
[01:53:17.891] Timer tick, asking Buildbucket for the build status
[01:53:18.203] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:18.203] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:59:0) after 1m0s
[01:54:18.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:59:0)
[01:54:18.345] Timer tick, asking Buildbucket for the build status
[01:54:18.665] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:18.665] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:60:0) after 1m0s
[01:55:18.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:60:0)
[01:55:18.768] Timer tick, asking Buildbucket for the build status
[01:55:19.054] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:19.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:61:0) after 1m0s
[01:56:19.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:61:0)
[01:56:19.114] Timer tick, asking Buildbucket for the build status
[01:56:19.277] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:19.277] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:62:0) after 1m0s
[01:57:19.349] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:62:0)
[01:57:19.349] Timer tick, asking Buildbucket for the build status
[01:57:19.588] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:19.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:63:0) after 1m0s
[01:58:19.758] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:63:0)
[01:58:19.758] Timer tick, asking Buildbucket for the build status
[01:58:19.908] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:19.908] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:64:0) after 1m0s
[01:59:20.001] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:64:0)
[01:59:20.001] Timer tick, asking Buildbucket for the build status
[01:59:20.552] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:20.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:65:0) after 1m0s
[02:00:20.624] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:65:0)
[02:00:20.624] Timer tick, asking Buildbucket for the build status
[02:00:21.018] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:21.018] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:66:0) after 1m0s
[02:01:21.052] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:66:0)
[02:01:21.052] Timer tick, asking Buildbucket for the build status
[02:01:21.215] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:21.215] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:67:0) after 1m0s
[02:02:21.304] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:67:0)
[02:02:21.304] Timer tick, asking Buildbucket for the build status
[02:02:21.554] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:21.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:68:0) after 1m0s
[02:03:21.582] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:68:0)
[02:03:21.582] Timer tick, asking Buildbucket for the build status
[02:03:22.113] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:22.113] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:69:0) after 1m0s
[02:04:22.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:69:0)
[02:04:22.175] Timer tick, asking Buildbucket for the build status
[02:04:24.651] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:24.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:70:0) after 1m0s
[02:05:24.738] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:70:0)
[02:05:24.738] Timer tick, asking Buildbucket for the build status
[02:05:24.879] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:24.879] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:71:0) after 1m0s
[02:06:24.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:71:0)
[02:06:24.967] Timer tick, asking Buildbucket for the build status
[02:06:25.384] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:25.384] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:72:0) after 1m0s
[02:07:25.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:72:0)
[02:07:25.448] Timer tick, asking Buildbucket for the build status
[02:07:25.647] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:25.647] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:73:0) after 1m0s
[02:08:25.702] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:73:0)
[02:08:25.702] Timer tick, asking Buildbucket for the build status
[02:08:25.934] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:25.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:74:0) after 1m0s
[02:09:25.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:74:0)
[02:09:25.991] Timer tick, asking Buildbucket for the build status
[02:09:26.550] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:26.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:75:0) after 1m0s
[02:10:26.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:75:0)
[02:10:26.527] Timer tick, asking Buildbucket for the build status
[02:10:26.945] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:26.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:76:0) after 1m0s
[02:11:26.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:76:0)
[02:11:26.966] Timer tick, asking Buildbucket for the build status
[02:11:27.347] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:27.348] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:77:0) after 1m0s
[02:12:27.372] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:77:0)
[02:12:27.372] Timer tick, asking Buildbucket for the build status
[02:12:28.089] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:28.089] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:78:0) after 1m0s
[02:13:28.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:78:0)
[02:13:28.148] Timer tick, asking Buildbucket for the build status
[02:13:28.444] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:28.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:79:0) after 1m0s
[02:14:28.482] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:79:0)
[02:14:28.482] Timer tick, asking Buildbucket for the build status
[02:14:28.689] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:28.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:80:0) after 1m0s
[02:15:28.732] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:80:0)
[02:15:28.732] Timer tick, asking Buildbucket for the build status
[02:15:28.978] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:28.978] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:81:0) after 1m0s
[02:16:29.008] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:81:0)
[02:16:29.008] Timer tick, asking Buildbucket for the build status
[02:16:29.163] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:29.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:82:0) after 1m0s
[02:17:29.192] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:82:0)
[02:17:29.192] Timer tick, asking Buildbucket for the build status
[02:17:29.627] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:29.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:83:0) after 1m0s
[02:18:29.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:83:0)
[02:18:29.656] Timer tick, asking Buildbucket for the build status
[02:18:29.883] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:29.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:84:0) after 1m0s
[02:19:30.019] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:84:0)
[02:19:30.019] Timer tick, asking Buildbucket for the build status
[02:19:30.559] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:30.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:85:0) after 1m0s
[02:20:30.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:85:0)
[02:20:30.610] Timer tick, asking Buildbucket for the build status
[02:20:30.934] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:30.934] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:86:0) after 1m0s
[02:21:31.016] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:86:0)
[02:21:31.016] Timer tick, asking Buildbucket for the build status
[02:21:31.949] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:31.949] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:87:0) after 1m0s
[02:22:31.940] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:87:0)
[02:22:31.940] Timer tick, asking Buildbucket for the build status
[02:22:32.616] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:32.616] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:88:0) after 1m0s
[02:23:32.645] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:88:0)
[02:23:32.645] Timer tick, asking Buildbucket for the build status
[02:23:32.803] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:32.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:89:0) after 1m0s
[02:24:32.920] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:89:0)
[02:24:32.920] Timer tick, asking Buildbucket for the build status
[02:24:33.170] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:33.170] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:90:0) after 1m0s
[02:25:33.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:90:0)
[02:25:33.278] Timer tick, asking Buildbucket for the build status
[02:25:33.657] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:33.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:91:0) after 1m0s
[02:26:33.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:91:0)
[02:26:33.793] Timer tick, asking Buildbucket for the build status
[02:26:34.971] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:34.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:92:0) after 1m0s
[02:27:35.062] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:92:0)
[02:27:35.063] Timer tick, asking Buildbucket for the build status
[02:27:35.459] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:35.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:93:0) after 1m0s
[02:28:35.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:93:0)
[02:28:35.465] Timer tick, asking Buildbucket for the build status
[02:28:35.610] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:35.610] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:94:0) after 1m0s
[02:29:35.644] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:94:0)
[02:29:35.644] Timer tick, asking Buildbucket for the build status
[02:29:35.985] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:35.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:95:0) after 1m0s
[02:30:36.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:95:0)
[02:30:36.048] Timer tick, asking Buildbucket for the build status
[02:30:36.608] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:36.608] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:96:0) after 1m0s
[02:31:36.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:96:0)
[02:31:36.609] Timer tick, asking Buildbucket for the build status
[02:31:37.064] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:37.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:97:0) after 1m0s
[02:32:37.093] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:97:0)
[02:32:37.093] Timer tick, asking Buildbucket for the build status
[02:32:38.529] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:38.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:98:0) after 1m0s
[02:33:38.508] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:98:0)
[02:33:38.508] Timer tick, asking Buildbucket for the build status
[02:33:38.728] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:38.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:99:0) after 1m0s
[02:34:38.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:99:0)
[02:34:38.717] Timer tick, asking Buildbucket for the build status
[02:34:39.892] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:39.892] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:100:0) after 1m0s
[02:35:39.947] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:100:0)
[02:35:39.947] Timer tick, asking Buildbucket for the build status
[02:35:40.065] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:40.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:101:0) after 1m0s
[02:36:40.089] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:101:0)
[02:36:40.089] Timer tick, asking Buildbucket for the build status
[02:36:40.329] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:40.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:102:0) after 1m0s
[02:37:40.390] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:102:0)
[02:37:40.390] Timer tick, asking Buildbucket for the build status
[02:37:41.388] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:41.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:103:0) after 1m0s
[02:38:41.479] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:103:0)
[02:38:41.479] Timer tick, asking Buildbucket for the build status
[02:38:41.845] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:41.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:104:0) after 1m0s
[02:39:41.911] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:104:0)
[02:39:41.911] Timer tick, asking Buildbucket for the build status
[02:39:42.148] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:42.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:105:0) after 1m0s
[02:40:42.177] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:105:0)
[02:40:42.177] Timer tick, asking Buildbucket for the build status
[02:40:42.307] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:42.307] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:106:0) after 1m0s
[02:41:42.365] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:106:0)
[02:41:42.365] Timer tick, asking Buildbucket for the build status
[02:41:42.725] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:42.725] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:107:0) after 1m0s
[02:42:42.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:107:0)
[02:42:42.748] Timer tick, asking Buildbucket for the build status
[02:42:43.069] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:43.069] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:108:0) after 1m0s
[02:43:43.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:108:0)
[02:43:43.161] Timer tick, asking Buildbucket for the build status
[02:43:43.287] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:43.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:109:0) after 1m0s
[02:44:43.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:109:0)
[02:44:43.322] Timer tick, asking Buildbucket for the build status
[02:44:43.743] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:43.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:110:0) after 1m0s
[02:45:43.765] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:110:0)
[02:45:43.765] Timer tick, asking Buildbucket for the build status
[02:45:44.144] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:44.144] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:111:0) after 1m0s
[02:46:44.254] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:111:0)
[02:46:44.254] Timer tick, asking Buildbucket for the build status
[02:46:44.443] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:44.443] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:112:0) after 1m0s
[02:47:44.466] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:112:0)
[02:47:44.466] Timer tick, asking Buildbucket for the build status
[02:47:44.871] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:44.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:113:0) after 1m0s
[02:48:44.891] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:113:0)
[02:48:44.891] Timer tick, asking Buildbucket for the build status
[02:48:45.053] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:45.053] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:114:0) after 1m0s
[02:49:45.232] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:114:0)
[02:49:45.232] Timer tick, asking Buildbucket for the build status
[02:49:45.694] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:45.694] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:115:0) after 1m0s
[02:50:45.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:115:0)
[02:50:45.768] Timer tick, asking Buildbucket for the build status
[02:50:46.229] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:46.229] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:116:0) after 1m0s
[02:51:46.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:116:0)
[02:51:46.286] Timer tick, asking Buildbucket for the build status
[02:51:46.455] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:46.455] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:117:0) after 1m0s
[02:52:46.504] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:117:0)
[02:52:46.504] Timer tick, asking Buildbucket for the build status
[02:52:47.814] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:47.814] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:118:0) after 1m0s
[02:53:47.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:118:0)
[02:53:47.834] Timer tick, asking Buildbucket for the build status
[02:53:48.386] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:48.386] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:119:0) after 1m0s
[02:54:48.419] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:119:0)
[02:54:48.419] Timer tick, asking Buildbucket for the build status
[02:54:48.571] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:48.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:120:0) after 1m0s
[02:55:48.631] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:120:0)
[02:55:48.631] Timer tick, asking Buildbucket for the build status
[02:55:48.843] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:48.843] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:121:0) after 1m0s
[02:56:48.875] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:121:0)
[02:56:48.875] Timer tick, asking Buildbucket for the build status
[02:56:49.137] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:49.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:122:0) after 1m0s
[02:57:49.125] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:122:0)
[02:57:49.125] Timer tick, asking Buildbucket for the build status
[02:57:49.631] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:49.631] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:123:0) after 1m0s
[02:58:49.664] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:123:0)
[02:58:49.664] Timer tick, asking Buildbucket for the build status
[02:58:49.959] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:49.959] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:124:0) after 1m0s
[02:59:49.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:124:0)
[02:59:49.980] Timer tick, asking Buildbucket for the build status
[02:59:50.211] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:50.211] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:125:0) after 1m0s
[03:00:50.359] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:125:0)
[03:00:50.359] Timer tick, asking Buildbucket for the build status
[03:00:51.240] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:51.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:126:0) after 1m0s
[03:01:51.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:126:0)
[03:01:51.320] Timer tick, asking Buildbucket for the build status
[03:01:51.674] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:51.674] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:127:0) after 1m0s
[03:02:51.799] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:127:0)
[03:02:51.799] Timer tick, asking Buildbucket for the build status
[03:02:52.642] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:52.642] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:128:0) after 1m0s
[03:03:52.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:128:0)
[03:03:52.772] Timer tick, asking Buildbucket for the build status
[03:03:52.902] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:52.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:129:0) after 1m0s
[03:04:52.967] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:129:0)
[03:04:52.967] Timer tick, asking Buildbucket for the build status
[03:04:56.873] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:56.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:130:0) after 1m0s
[03:05:56.942] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:130:0)
[03:05:56.942] Timer tick, asking Buildbucket for the build status
[03:05:57.216] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:57.216] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:131:0) after 1m0s
[03:06:57.336] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:131:0)
[03:06:57.336] Timer tick, asking Buildbucket for the build status
[03:06:57.489] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:57.489] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:132:0) after 1m0s
[03:07:57.593] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:132:0)
[03:07:57.593] Timer tick, asking Buildbucket for the build status
[03:07:57.794] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:57.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:133:0) after 1m0s
[03:08:57.866] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:133:0)
[03:08:57.866] Timer tick, asking Buildbucket for the build status
[03:08:58.062] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:58.062] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:134:0) after 1m0s
[03:09:58.145] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:134:0)
[03:09:58.145] Timer tick, asking Buildbucket for the build status
[03:09:58.298] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:58.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:135:0) after 1m0s
[03:10:58.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:135:0)
[03:10:58.550] Timer tick, asking Buildbucket for the build status
[03:10:58.871] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:58.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:136:0) after 1m0s
[03:11:59.151] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:136:0)
[03:11:59.151] Timer tick, asking Buildbucket for the build status
[03:12:00.183] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:00.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:137:0) after 1m0s
[03:13:00.340] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:137:0)
[03:13:00.340] Timer tick, asking Buildbucket for the build status
[03:13:00.594] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:00.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:138:0) after 1m0s
[03:14:00.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:138:0)
[03:14:00.648] Timer tick, asking Buildbucket for the build status
[03:14:01.249] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:01.249] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:139:0) after 1m0s
[03:15:02.481] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:139:0)
[03:15:02.481] Timer tick, asking Buildbucket for the build status
[03:15:02.688] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:02.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:140:0) after 1m0s
[03:16:02.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:140:0)
[03:16:02.861] Timer tick, asking Buildbucket for the build status
[03:16:03.064] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:03.064] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:141:0) after 1m0s
[03:17:03.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:141:0)
[03:17:03.152] Timer tick, asking Buildbucket for the build status
[03:17:03.289] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:03.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:142:0) after 1m0s
[03:18:03.379] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:142:0)
[03:18:03.379] Timer tick, asking Buildbucket for the build status
[03:18:03.662] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:03.662] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:143:0) after 1m0s
[03:19:03.687] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:143:0)
[03:19:03.687] Timer tick, asking Buildbucket for the build status
[03:19:03.901] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:03.901] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:144:0) after 1m0s
[03:20:04.196] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:144:0)
[03:20:04.196] Timer tick, asking Buildbucket for the build status
[03:20:04.553] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:04.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:145:0) after 1m0s
[03:21:04.686] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:145:0)
[03:21:04.686] Timer tick, asking Buildbucket for the build status
[03:21:04.910] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:04.910] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:146:0) after 1m0s
[03:22:05.117] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:146:0)
[03:22:05.117] Timer tick, asking Buildbucket for the build status
[03:22:05.649] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:05.649] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:147:0) after 1m0s
[03:23:05.814] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:147:0)
[03:23:05.814] Timer tick, asking Buildbucket for the build status
[03:23:06.807] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:06.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:148:0) after 1m0s
[03:24:07.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:148:0)
[03:24:07.084] Timer tick, asking Buildbucket for the build status
[03:24:07.556] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:07.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:149:0) after 1m0s
[03:25:07.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:149:0)
[03:25:07.637] Timer tick, asking Buildbucket for the build status
[03:25:07.812] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:07.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:150:0) after 1m0s
[03:26:08.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:150:0)
[03:26:08.064] Timer tick, asking Buildbucket for the build status
[03:26:10.902] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:10.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:151:0) after 1m0s
[03:27:11.013] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:151:0)
[03:27:11.013] Timer tick, asking Buildbucket for the build status
[03:27:11.275] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:11.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:152:0) after 1m0s
[03:28:11.505] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:152:0)
[03:28:11.505] Timer tick, asking Buildbucket for the build status
[03:28:11.640] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:11.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:153:0) after 1m0s
[03:29:11.768] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:153:0)
[03:29:11.768] Timer tick, asking Buildbucket for the build status
[03:29:12.169] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:12.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:154:0) after 1m0s
[03:30:12.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:154:0)
[03:30:12.265] Timer tick, asking Buildbucket for the build status
[03:30:13.161] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:13.161] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:155:0) after 1m0s
[03:31:13.300] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:155:0)
[03:31:13.300] Timer tick, asking Buildbucket for the build status
[03:31:13.461] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:13.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:156:0) after 1m0s
[03:32:13.535] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:156:0)
[03:32:13.535] Timer tick, asking Buildbucket for the build status
[03:32:14.029] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:14.029] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:157:0) after 1m0s
[03:33:14.026] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:157:0)
[03:33:14.026] Timer tick, asking Buildbucket for the build status
[03:33:14.335] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:14.335] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:158:0) after 1m0s
[03:34:14.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:158:0)
[03:34:14.544] Timer tick, asking Buildbucket for the build status
[03:34:14.795] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:14.795] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:159:0) after 1m0s
[03:35:14.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:159:0)
[03:35:14.930] Timer tick, asking Buildbucket for the build status
[03:35:16.197] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:16.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:160:0) after 1m0s
[03:36:16.358] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:160:0)
[03:36:16.358] Timer tick, asking Buildbucket for the build status
[03:36:16.508] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:16.508] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:161:0) after 1m0s
[03:37:16.659] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:161:0)
[03:37:16.659] Timer tick, asking Buildbucket for the build status
[03:37:16.979] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:16.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:162:0) after 1m0s
[03:38:17.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:162:0)
[03:38:17.111] Timer tick, asking Buildbucket for the build status
[03:38:17.313] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:17.313] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:163:0) after 1m0s
[03:39:17.335] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:163:0)
[03:39:17.335] Timer tick, asking Buildbucket for the build status
[03:39:17.467] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:17.467] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:164:0) after 1m0s
[03:40:17.526] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:164:0)
[03:40:17.526] Timer tick, asking Buildbucket for the build status
[03:40:18.135] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:18.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:165:0) after 1m0s
[03:41:18.395] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:165:0)
[03:41:18.395] Timer tick, asking Buildbucket for the build status
[03:41:18.559] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:18.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:166:0) after 1m0s
[03:42:18.734] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:166:0)
[03:42:18.734] Timer tick, asking Buildbucket for the build status
[03:42:18.913] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:18.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:167:0) after 1m0s
[03:43:18.992] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:167:0)
[03:43:18.992] Timer tick, asking Buildbucket for the build status
[03:43:19.205] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:19.206] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:168:0) after 1m0s
[03:44:19.242] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:168:0)
[03:44:19.242] Timer tick, asking Buildbucket for the build status
[03:44:19.628] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:19.628] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:169:0) after 1m0s
[03:45:19.683] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:169:0)
[03:45:19.683] Timer tick, asking Buildbucket for the build status
[03:45:19.889] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:19.889] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:170:0) after 1m0s
[03:46:19.917] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:170:0)
[03:46:19.917] Timer tick, asking Buildbucket for the build status
[03:46:20.151] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:20.151] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:171:0) after 1m0s
[03:47:20.247] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:171:0)
[03:47:20.247] Timer tick, asking Buildbucket for the build status
[03:47:20.540] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:20.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:172:0) after 1m0s
[03:48:20.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:172:0)
[03:48:20.652] Timer tick, asking Buildbucket for the build status
[03:48:20.833] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:20.833] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:173:0) after 1m0s
[03:49:20.861] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:173:0)
[03:49:20.861] Timer tick, asking Buildbucket for the build status
[03:49:20.979] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:20.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:174:0) after 1m0s
[03:50:21.005] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:174:0)
[03:50:21.005] Timer tick, asking Buildbucket for the build status
[03:50:21.134] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:21.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:175:0) after 1m0s
[03:51:21.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:175:0)
[03:51:21.200] Timer tick, asking Buildbucket for the build status
[03:51:21.475] Build 8878918800365778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:21.475] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9044348863246954144:176:0) after 1m0s
[03:52:11.284] Received PubSub notification, asking Buildbucket for the build status
[03:52:11.371] Build 8878918800365778528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:52:11.371] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.toolchain",
    "canary_preference": "PROD",
    "completed_ts": "1590810729191083",
    "created_by": "project:nacl",
    "created_ts": "1590800204842210",
    "id": "8878918800365778528",
    "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\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"title\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/bbb0247b832619c6c1f43da4e1c9182519824e2a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}}",
    "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\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"title\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/bbb0247b832619c6c1f43da4e1c9182519824e2a\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-sea0\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7070, \"got_revision\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"mastername\": \"client.nacl.toolchain\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"bbb0247b832619c6c1f43da4e1c9182519824e2a\", \"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-sea0\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"5142-e16b32c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
    "service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1590801657568252",
    "status": "COMPLETED",
    "status_changed_ts": "1590810730288344",
    "tags": [
      "build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7070",
      "builder:linux-pnacl-x86_64",
      "buildset:commit/git/bbb0247b832619c6c1f43da4e1c9182519824e2a",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/bbb0247b832619c6c1f43da4e1c9182519824e2a",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9044348863246954144",
      "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/8878918800365778528/+/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:4c7be65645f0e610",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1590810730350197",
    "url": "https://ci.chromium.org/b/8878918800365778528",
    "utcnow_ts": "1590810731361064"
  }
}
[03:52:11.371] Invocation finished in 2h55m28.660252753s with status SUCCEEDED