buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.nacl.ci"
  builder: "linux-64-validator-opt"
>

• ffed96d1528df9f114a30b14d4eb06120c8fb022 (5 years ago)

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

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

[04:08:50.177] New invocation is queued and will start shortly
[04:08:51.316] Starting the invocation (attempt 1)
[04:08:51.357] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:08:51.357] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:08:51.357] Buildbucket request:
{
  "bucket": "luci.nacl.ci",
  "client_operation_id": "9050406772586778608",
  "parameters_json": "{\"builder_name\":\"linux-64-validator-opt\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"title\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:linux-64-validator-opt",
    "scheduler_invocation_id:9050406772586778608",
    "scheduler_job_id:nacl/linux-64-validator-opt",
    "user_agent:luci-scheduler",
    "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
    "gitiles_ref:refs/heads/master"
  ]
}
[04:08:52.650] Buildbucket response:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "created_by": "project:nacl",
    "created_ts": "1585022931477046",
    "id": "8884976710561874224",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "project": "nacl",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1585022932234168",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/129",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050406772586778608",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884976710561874224/+/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": "1585022932234291",
    "url": "https://ci.chromium.org/b/8884976710561874224",
    "utcnow_ts": "1585022932639304"
  }
}
[04:08:52.650] Task URL: https://ci.chromium.org/b/8884976710561874224
[04:08:52.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:2:0) after 1m0s
[04:09:08.078] Received PubSub notification, asking Buildbucket for the build status
[04:09:08.449] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:52.677] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:2:0)
[04:09:52.677] Timer tick, asking Buildbucket for the build status
[04:09:52.939] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:52.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:4:0) after 1m0s
[04:10:53.004] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:4:0)
[04:10:53.004] Timer tick, asking Buildbucket for the build status
[04:10:53.195] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:53.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:5:0) after 1m0s
[04:11:53.233] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:5:0)
[04:11:53.233] Timer tick, asking Buildbucket for the build status
[04:11:53.626] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:53.626] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:6:0) after 1m0s
[04:12:53.643] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:6:0)
[04:12:53.643] Timer tick, asking Buildbucket for the build status
[04:12:53.775] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:53.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:7:0) after 1m0s
[04:13:53.797] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:7:0)
[04:13:53.797] Timer tick, asking Buildbucket for the build status
[04:13:54.902] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:54.902] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:8:0) after 1m0s
[04:14:55.001] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:8:0)
[04:14:55.001] Timer tick, asking Buildbucket for the build status
[04:14:55.162] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:55.162] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:9:0) after 1m0s
[04:15:55.181] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:9:0)
[04:15:55.181] Timer tick, asking Buildbucket for the build status
[04:15:55.289] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:55.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:10:0) after 1m0s
[04:16:55.289] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:10:0)
[04:16:55.289] Timer tick, asking Buildbucket for the build status
[04:16:55.422] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:55.422] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:11:0) after 1m0s
[04:17:55.439] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:11:0)
[04:17:55.439] Timer tick, asking Buildbucket for the build status
[04:17:55.550] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:55.550] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:12:0) after 1m0s
[04:18:55.573] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:12:0)
[04:18:55.573] Timer tick, asking Buildbucket for the build status
[04:18:55.640] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:55.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:13:0) after 1m0s
[04:19:55.664] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:13:0)
[04:19:55.664] Timer tick, asking Buildbucket for the build status
[04:19:55.778] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:55.778] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:14:0) after 1m0s
[04:20:55.784] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:14:0)
[04:20:55.784] Timer tick, asking Buildbucket for the build status
[04:20:55.904] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:55.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:15:0) after 1m0s
[04:21:55.932] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:15:0)
[04:21:55.932] Timer tick, asking Buildbucket for the build status
[04:21:56.103] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:56.103] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:16:0) after 1m0s
[04:22:56.122] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:16:0)
[04:22:56.122] Timer tick, asking Buildbucket for the build status
[04:22:56.266] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:56.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:17:0) after 1m0s
[04:23:56.318] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:17:0)
[04:23:56.318] Timer tick, asking Buildbucket for the build status
[04:23:56.596] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:56.596] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:18:0) after 1m0s
[04:24:56.618] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:18:0)
[04:24:56.618] Timer tick, asking Buildbucket for the build status
[04:24:56.758] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:56.758] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:19:0) after 1m0s
[04:25:56.891] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:19:0)
[04:25:56.891] Timer tick, asking Buildbucket for the build status
[04:25:56.971] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:56.971] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:20:0) after 1m0s
[04:26:57.119] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:20:0)
[04:26:57.119] Timer tick, asking Buildbucket for the build status
[04:26:57.358] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:57.358] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:21:0) after 1m0s
[04:27:57.401] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:21:0)
[04:27:57.401] Timer tick, asking Buildbucket for the build status
[04:27:57.570] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:57.570] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:22:0) after 1m0s
[04:28:57.615] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:22:0)
[04:28:57.615] Timer tick, asking Buildbucket for the build status
[04:28:57.731] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:57.731] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:23:0) after 1m0s
[04:29:57.950] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:23:0)
[04:29:57.950] Timer tick, asking Buildbucket for the build status
[04:29:58.093] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:58.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:24:0) after 1m0s
[04:30:58.081] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:24:0)
[04:30:58.081] Timer tick, asking Buildbucket for the build status
[04:30:58.194] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:58.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:25:0) after 1m0s
[04:31:58.237] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:25:0)
[04:31:58.238] Timer tick, asking Buildbucket for the build status
[04:31:58.370] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:26:0) after 1m0s
[04:32:58.408] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:26:0)
[04:32:58.408] Timer tick, asking Buildbucket for the build status
[04:32:58.528] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:58.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:27:0) after 1m0s
[04:33:58.674] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:27:0)
[04:33:58.674] Timer tick, asking Buildbucket for the build status
[04:33:58.799] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:58.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:28:0) after 1m0s
[04:34:58.811] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:28:0)
[04:34:58.811] Timer tick, asking Buildbucket for the build status
[04:34:58.904] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:58.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:29:0) after 1m0s
[04:35:58.998] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:29:0)
[04:35:58.998] Timer tick, asking Buildbucket for the build status
[04:35:59.360] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.360] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:30:0) after 1m0s
[04:36:59.336] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:30:0)
[04:36:59.336] Timer tick, asking Buildbucket for the build status
[04:36:59.453] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.453] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:31:0) after 1m0s
[04:37:59.606] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:31:0)
[04:37:59.606] Timer tick, asking Buildbucket for the build status
[04:37:59.687] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:32:0) after 1m0s
[04:38:59.773] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:32:0)
[04:38:59.773] Timer tick, asking Buildbucket for the build status
[04:38:59.963] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.963] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:33:0) after 1m0s
[04:40:00.030] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:33:0)
[04:40:00.030] Timer tick, asking Buildbucket for the build status
[04:40:00.188] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:34:0) after 1m0s
[04:41:00.216] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:34:0)
[04:41:00.216] Timer tick, asking Buildbucket for the build status
[04:41:01.275] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:01.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:35:0) after 1m0s
[04:42:01.525] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:35:0)
[04:42:01.525] Timer tick, asking Buildbucket for the build status
[04:42:01.848] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:01.848] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:36:0) after 1m0s
[04:43:01.842] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:36:0)
[04:43:01.842] Timer tick, asking Buildbucket for the build status
[04:43:02.163] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:02.163] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:37:0) after 1m0s
[04:44:02.266] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:37:0)
[04:44:02.266] Timer tick, asking Buildbucket for the build status
[04:44:02.469] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:02.469] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:38:0) after 1m0s
[04:45:02.482] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:38:0)
[04:45:02.482] Timer tick, asking Buildbucket for the build status
[04:45:02.967] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:02.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:39:0) after 1m0s
[04:46:02.977] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:39:0)
[04:46:02.977] Timer tick, asking Buildbucket for the build status
[04:46:03.107] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:03.107] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:40:0) after 1m0s
[04:47:03.179] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:40:0)
[04:47:03.179] Timer tick, asking Buildbucket for the build status
[04:47:03.582] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:03.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:41:0) after 1m0s
[04:48:03.642] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:41:0)
[04:48:03.642] Timer tick, asking Buildbucket for the build status
[04:48:03.768] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:03.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:42:0) after 1m0s
[04:49:03.787] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:42:0)
[04:49:03.787] Timer tick, asking Buildbucket for the build status
[04:49:03.968] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:03.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:43:0) after 1m0s
[04:50:04.113] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:43:0)
[04:50:04.113] Timer tick, asking Buildbucket for the build status
[04:50:04.327] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:04.327] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:44:0) after 1m0s
[04:51:04.412] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:44:0)
[04:51:04.412] Timer tick, asking Buildbucket for the build status
[04:51:04.565] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:04.565] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:45:0) after 1m0s
[04:52:04.639] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:45:0)
[04:52:04.639] Timer tick, asking Buildbucket for the build status
[04:52:04.924] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:04.924] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:46:0) after 1m0s
[04:53:05.030] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:46:0)
[04:53:05.030] Timer tick, asking Buildbucket for the build status
[04:53:05.137] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:05.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:47:0) after 1m0s
[04:54:05.458] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:47:0)
[04:54:05.458] Timer tick, asking Buildbucket for the build status
[04:54:05.585] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:05.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:48:0) after 1m0s
[04:55:05.686] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:48:0)
[04:55:05.686] Timer tick, asking Buildbucket for the build status
[04:55:05.794] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:05.794] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:49:0) after 1m0s
[04:56:06.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:49:0)
[04:56:06.123] Timer tick, asking Buildbucket for the build status
[04:56:06.972] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:06.972] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:50:0) after 1m0s
[04:57:07.110] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:50:0)
[04:57:07.110] Timer tick, asking Buildbucket for the build status
[04:57:07.288] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:07.288] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:51:0) after 1m0s
[04:58:07.514] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:51:0)
[04:58:07.514] Timer tick, asking Buildbucket for the build status
[04:58:07.873] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:07.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:52:0) after 1m0s
[04:59:08.165] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:52:0)
[04:59:08.165] Timer tick, asking Buildbucket for the build status
[04:59:08.509] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:08.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:53:0) after 1m0s
[05:00:08.705] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:53:0)
[05:00:08.705] Timer tick, asking Buildbucket for the build status
[05:00:08.818] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:08.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:54:0) after 1m0s
[05:01:10.894] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:54:0)
[05:01:10.894] Timer tick, asking Buildbucket for the build status
[05:01:11.021] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:11.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:55:0) after 1m0s
[05:02:11.103] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:55:0)
[05:02:11.103] Timer tick, asking Buildbucket for the build status
[05:02:12.337] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:12.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:56:0) after 1m0s
[05:03:12.481] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:56:0)
[05:03:12.481] Timer tick, asking Buildbucket for the build status
[05:03:12.689] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:12.689] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:57:0) after 1m0s
[05:04:12.789] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:57:0)
[05:04:12.790] Timer tick, asking Buildbucket for the build status
[05:04:13.672] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:13.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:58:0) after 1m0s
[05:05:13.968] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:58:0)
[05:05:13.968] Timer tick, asking Buildbucket for the build status
[05:05:14.091] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:14.091] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:59:0) after 1m0s
[05:06:14.220] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:59:0)
[05:06:14.220] Timer tick, asking Buildbucket for the build status
[05:06:14.377] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:14.377] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:60:0) after 1m0s
[05:07:14.397] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:60:0)
[05:07:14.397] Timer tick, asking Buildbucket for the build status
[05:07:14.634] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:14.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:61:0) after 1m0s
[05:08:14.762] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:61:0)
[05:08:14.762] Timer tick, asking Buildbucket for the build status
[05:08:14.868] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:14.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:62:0) after 1m0s
[05:09:14.907] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:62:0)
[05:09:14.907] Timer tick, asking Buildbucket for the build status
[05:09:15.232] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:15.232] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:63:0) after 1m0s
[05:10:15.305] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:63:0)
[05:10:15.305] Timer tick, asking Buildbucket for the build status
[05:10:15.427] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:15.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:64:0) after 1m0s
[05:11:15.510] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:64:0)
[05:11:15.510] Timer tick, asking Buildbucket for the build status
[05:11:15.712] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:15.712] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:65:0) after 1m0s
[05:12:15.733] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:65:0)
[05:12:15.733] Timer tick, asking Buildbucket for the build status
[05:12:15.854] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:15.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:66:0) after 1m0s
[05:13:16.073] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:66:0)
[05:13:16.073] Timer tick, asking Buildbucket for the build status
[05:13:16.278] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:16.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:67:0) after 1m0s
[05:14:16.295] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:67:0)
[05:14:16.295] Timer tick, asking Buildbucket for the build status
[05:14:16.416] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:16.416] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:68:0) after 1m0s
[05:15:16.544] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:68:0)
[05:15:16.544] Timer tick, asking Buildbucket for the build status
[05:15:16.644] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:16.644] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:69:0) after 1m0s
[05:16:16.673] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:69:0)
[05:16:16.673] Timer tick, asking Buildbucket for the build status
[05:16:17.707] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:17.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:70:0) after 1m0s
[05:17:17.821] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:70:0)
[05:17:17.821] Timer tick, asking Buildbucket for the build status
[05:17:17.952] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:17.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:71:0) after 1m0s
[05:18:17.966] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:71:0)
[05:18:17.966] Timer tick, asking Buildbucket for the build status
[05:18:18.102] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:18.102] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:72:0) after 1m0s
[05:19:18.123] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:72:0)
[05:19:18.123] Timer tick, asking Buildbucket for the build status
[05:19:18.237] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:18.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:73:0) after 1m0s
[05:20:18.324] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:73:0)
[05:20:18.324] Timer tick, asking Buildbucket for the build status
[05:20:18.528] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:18.528] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:74:0) after 1m0s
[05:21:18.553] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:74:0)
[05:21:18.553] Timer tick, asking Buildbucket for the build status
[05:21:18.656] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:18.656] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:75:0) after 1m0s
[05:22:18.694] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:75:0)
[05:22:18.694] Timer tick, asking Buildbucket for the build status
[05:22:18.883] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:18.883] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:76:0) after 1m0s
[05:23:18.883] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:76:0)
[05:23:18.883] Timer tick, asking Buildbucket for the build status
[05:23:19.049] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:19.049] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:77:0) after 1m0s
[05:24:19.177] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:77:0)
[05:24:19.177] Timer tick, asking Buildbucket for the build status
[05:24:19.290] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:19.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:78:0) after 1m0s
[05:25:19.360] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:78:0)
[05:25:19.360] Timer tick, asking Buildbucket for the build status
[05:25:19.460] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:19.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:79:0) after 1m0s
[05:26:19.496] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:79:0)
[05:26:19.496] Timer tick, asking Buildbucket for the build status
[05:26:19.622] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:19.622] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:80:0) after 1m0s
[05:27:19.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:80:0)
[05:27:19.667] Timer tick, asking Buildbucket for the build status
[05:27:19.853] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:19.853] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:81:0) after 1m0s
[05:28:19.936] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:81:0)
[05:28:19.936] Timer tick, asking Buildbucket for the build status
[05:28:20.096] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:20.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:82:0) after 1m0s
[05:29:20.270] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:82:0)
[05:29:20.270] Timer tick, asking Buildbucket for the build status
[05:29:20.540] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:20.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:83:0) after 1m0s
[05:30:20.644] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:83:0)
[05:30:20.644] Timer tick, asking Buildbucket for the build status
[05:30:20.832] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:20.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:84:0) after 1m0s
[05:31:20.838] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:84:0)
[05:31:20.838] Timer tick, asking Buildbucket for the build status
[05:31:21.258] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:21.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:85:0) after 1m0s
[05:32:21.327] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:85:0)
[05:32:21.327] Timer tick, asking Buildbucket for the build status
[05:32:21.438] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:21.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:86:0) after 1m0s
[05:33:21.546] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:86:0)
[05:33:21.546] Timer tick, asking Buildbucket for the build status
[05:33:21.682] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:21.682] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:87:0) after 1m0s
[05:34:21.700] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:87:0)
[05:34:21.700] Timer tick, asking Buildbucket for the build status
[05:34:21.802] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:21.802] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:88:0) after 1m0s
[05:35:21.864] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:88:0)
[05:35:21.864] Timer tick, asking Buildbucket for the build status
[05:35:22.068] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:22.068] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:89:0) after 1m0s
[05:36:22.071] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:89:0)
[05:36:22.071] Timer tick, asking Buildbucket for the build status
[05:36:22.345] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:22.345] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:90:0) after 1m0s
[05:37:22.344] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:90:0)
[05:37:22.344] Timer tick, asking Buildbucket for the build status
[05:37:22.504] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:22.504] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:91:0) after 1m0s
[05:38:22.537] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:91:0)
[05:38:22.537] Timer tick, asking Buildbucket for the build status
[05:38:22.658] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:22.658] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:92:0) after 1m0s
[05:39:22.932] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:92:0)
[05:39:22.932] Timer tick, asking Buildbucket for the build status
[05:39:23.071] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:23.071] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:93:0) after 1m0s
[05:40:23.330] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:93:0)
[05:40:23.330] Timer tick, asking Buildbucket for the build status
[05:40:23.450] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:23.450] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:94:0) after 1m0s
[05:41:24.154] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:94:0)
[05:41:24.154] Timer tick, asking Buildbucket for the build status
[05:41:24.280] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:24.280] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:95:0) after 1m0s
[05:42:24.454] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:95:0)
[05:42:24.454] Timer tick, asking Buildbucket for the build status
[05:42:24.625] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:24.625] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:96:0) after 1m0s
[05:43:24.752] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:96:0)
[05:43:24.752] Timer tick, asking Buildbucket for the build status
[05:43:24.850] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:24.850] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:97:0) after 1m0s
[05:44:24.993] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:97:0)
[05:44:24.993] Timer tick, asking Buildbucket for the build status
[05:44:25.263] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:25.263] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:98:0) after 1m0s
[05:45:25.290] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:98:0)
[05:45:25.290] Timer tick, asking Buildbucket for the build status
[05:45:25.401] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:25.401] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:99:0) after 1m0s
[05:46:25.550] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:99:0)
[05:46:25.550] Timer tick, asking Buildbucket for the build status
[05:46:25.973] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:25.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:100:0) after 1m0s
[05:47:26.023] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:100:0)
[05:47:26.023] Timer tick, asking Buildbucket for the build status
[05:47:26.135] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:26.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:101:0) after 1m0s
[05:48:26.194] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:101:0)
[05:48:26.194] Timer tick, asking Buildbucket for the build status
[05:48:27.041] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:27.042] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:102:0) after 1m0s
[05:49:27.131] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:102:0)
[05:49:27.131] Timer tick, asking Buildbucket for the build status
[05:49:27.397] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:27.397] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:103:0) after 1m0s
[05:50:27.426] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:103:0)
[05:50:27.427] Timer tick, asking Buildbucket for the build status
[05:50:27.509] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:27.509] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:104:0) after 1m0s
[05:51:27.582] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:104:0)
[05:51:27.582] Timer tick, asking Buildbucket for the build status
[05:51:27.859] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:27.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:105:0) after 1m0s
[05:52:27.906] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:105:0)
[05:52:27.906] Timer tick, asking Buildbucket for the build status
[05:52:28.100] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:28.100] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:106:0) after 1m0s
[05:53:28.140] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:106:0)
[05:53:28.140] Timer tick, asking Buildbucket for the build status
[05:53:28.250] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:28.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:107:0) after 1m0s
[05:54:28.281] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:107:0)
[05:54:28.281] Timer tick, asking Buildbucket for the build status
[05:54:28.397] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:28.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:108:0) after 1m0s
[05:55:28.495] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:108:0)
[05:55:28.495] Timer tick, asking Buildbucket for the build status
[05:55:28.659] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:28.659] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:109:0) after 1m0s
[05:56:28.685] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:109:0)
[05:56:28.685] Timer tick, asking Buildbucket for the build status
[05:56:28.828] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:28.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:110:0) after 1m0s
[05:57:28.895] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:110:0)
[05:57:28.895] Timer tick, asking Buildbucket for the build status
[05:57:29.025] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:29.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:111:0) after 1m0s
[05:58:29.252] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:111:0)
[05:58:29.252] Timer tick, asking Buildbucket for the build status
[05:58:29.372] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:29.372] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:112:0) after 1m0s
[05:59:29.479] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:112:0)
[05:59:29.479] Timer tick, asking Buildbucket for the build status
[05:59:29.603] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:29.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:113:0) after 1m0s
[06:00:30.024] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:113:0)
[06:00:30.024] Timer tick, asking Buildbucket for the build status
[06:00:30.189] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:30.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:114:0) after 1m0s
[06:01:30.247] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:114:0)
[06:01:30.247] Timer tick, asking Buildbucket for the build status
[06:01:31.057] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:31.057] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:115:0) after 1m0s
[06:02:31.092] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:115:0)
[06:02:31.092] Timer tick, asking Buildbucket for the build status
[06:02:31.267] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:31.267] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:116:0) after 1m0s
[06:03:31.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:116:0)
[06:03:31.298] Timer tick, asking Buildbucket for the build status
[06:03:31.418] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:31.418] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:117:0) after 1m0s
[06:04:31.545] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:117:0)
[06:04:31.545] Timer tick, asking Buildbucket for the build status
[06:04:31.670] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:31.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:118:0) after 1m0s
[06:05:31.721] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:118:0)
[06:05:31.721] Timer tick, asking Buildbucket for the build status
[06:05:31.861] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:31.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:119:0) after 1m0s
[06:06:31.857] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:119:0)
[06:06:31.857] Timer tick, asking Buildbucket for the build status
[06:06:31.964] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:31.964] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:120:0) after 1m0s
[06:07:32.047] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:120:0)
[06:07:32.047] Timer tick, asking Buildbucket for the build status
[06:07:32.321] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:32.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:121:0) after 1m0s
[06:08:32.351] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:121:0)
[06:08:32.351] Timer tick, asking Buildbucket for the build status
[06:08:32.620] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:32.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:122:0) after 1m0s
[06:09:32.641] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:122:0)
[06:09:32.641] Timer tick, asking Buildbucket for the build status
[06:09:32.759] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:32.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:123:0) after 1m0s
[06:10:32.782] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:123:0)
[06:10:32.782] Timer tick, asking Buildbucket for the build status
[06:10:32.961] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:32.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:124:0) after 1m0s
[06:11:32.945] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:124:0)
[06:11:32.945] Timer tick, asking Buildbucket for the build status
[06:11:33.066] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:33.066] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:125:0) after 1m0s
[06:12:33.106] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:125:0)
[06:12:33.106] Timer tick, asking Buildbucket for the build status
[06:12:33.207] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:33.207] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:126:0) after 1m0s
[06:13:33.227] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:126:0)
[06:13:33.227] Timer tick, asking Buildbucket for the build status
[06:13:33.366] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:33.366] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:127:0) after 1m0s
[06:14:33.412] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:127:0)
[06:14:33.412] Timer tick, asking Buildbucket for the build status
[06:14:33.531] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:33.531] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:128:0) after 1m0s
[06:15:33.575] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:128:0)
[06:15:33.575] Timer tick, asking Buildbucket for the build status
[06:15:33.743] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:33.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:129:0) after 1m0s
[06:16:33.812] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:129:0)
[06:16:33.812] Timer tick, asking Buildbucket for the build status
[06:16:34.261] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:34.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:130:0) after 1m0s
[06:17:34.291] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:130:0)
[06:17:34.291] Timer tick, asking Buildbucket for the build status
[06:17:35.023] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:35.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:131:0) after 1m0s
[06:18:35.066] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:131:0)
[06:18:35.066] Timer tick, asking Buildbucket for the build status
[06:18:35.190] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:35.190] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:132:0) after 1m0s
[06:19:35.246] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:132:0)
[06:19:35.246] Timer tick, asking Buildbucket for the build status
[06:19:35.370] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:35.370] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:133:0) after 1m0s
[06:20:35.390] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:133:0)
[06:20:35.390] Timer tick, asking Buildbucket for the build status
[06:20:35.510] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:35.510] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:134:0) after 1m0s
[06:21:35.635] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:134:0)
[06:21:35.635] Timer tick, asking Buildbucket for the build status
[06:21:35.832] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:35.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:135:0) after 1m0s
[06:22:35.853] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:135:0)
[06:22:35.853] Timer tick, asking Buildbucket for the build status
[06:22:36.286] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:36.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:136:0) after 1m0s
[06:23:36.433] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:136:0)
[06:23:36.433] Timer tick, asking Buildbucket for the build status
[06:23:36.603] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:36.603] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:137:0) after 1m0s
[06:24:36.633] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:137:0)
[06:24:36.633] Timer tick, asking Buildbucket for the build status
[06:24:36.775] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:36.775] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:138:0) after 1m0s
[06:25:36.794] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:138:0)
[06:25:36.794] Timer tick, asking Buildbucket for the build status
[06:25:37.035] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:37.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:139:0) after 1m0s
[06:26:37.096] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:139:0)
[06:26:37.096] Timer tick, asking Buildbucket for the build status
[06:26:37.252] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:37.252] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:140:0) after 1m0s
[06:27:37.315] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:140:0)
[06:27:37.315] Timer tick, asking Buildbucket for the build status
[06:27:38.037] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:38.037] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:141:0) after 1m0s
[06:28:38.062] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:141:0)
[06:28:38.062] Timer tick, asking Buildbucket for the build status
[06:28:38.213] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:38.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:142:0) after 1m0s
[06:29:38.242] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:142:0)
[06:29:38.242] Timer tick, asking Buildbucket for the build status
[06:29:38.383] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:38.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:143:0) after 1m0s
[06:30:38.428] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:143:0)
[06:30:38.428] Timer tick, asking Buildbucket for the build status
[06:30:38.669] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:38.669] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:144:0) after 1m0s
[06:31:38.752] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:144:0)
[06:31:38.752] Timer tick, asking Buildbucket for the build status
[06:31:38.871] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:38.871] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:145:0) after 1m0s
[06:32:38.933] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:145:0)
[06:32:38.933] Timer tick, asking Buildbucket for the build status
[06:32:39.792] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:39.792] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:146:0) after 1m0s
[06:33:39.876] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:146:0)
[06:33:39.876] Timer tick, asking Buildbucket for the build status
[06:33:39.939] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:39.939] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:147:0) after 1m0s
[06:34:40.062] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:147:0)
[06:34:40.062] Timer tick, asking Buildbucket for the build status
[06:34:42.181] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:42.181] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:148:0) after 1m0s
[06:35:42.428] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:148:0)
[06:35:42.428] Timer tick, asking Buildbucket for the build status
[06:35:42.554] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:42.554] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:149:0) after 1m0s
[06:36:42.745] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:149:0)
[06:36:42.745] Timer tick, asking Buildbucket for the build status
[06:36:43.174] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:43.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:150:0) after 1m0s
[06:37:43.243] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:150:0)
[06:37:43.243] Timer tick, asking Buildbucket for the build status
[06:37:43.553] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:43.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:151:0) after 1m0s
[06:38:43.578] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:151:0)
[06:38:43.578] Timer tick, asking Buildbucket for the build status
[06:38:43.679] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:43.679] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:152:0) after 1m0s
[06:39:43.676] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:152:0)
[06:39:43.676] Timer tick, asking Buildbucket for the build status
[06:39:43.854] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:43.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:153:0) after 1m0s
[06:40:43.925] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:153:0)
[06:40:43.925] Timer tick, asking Buildbucket for the build status
[06:40:44.044] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:44.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:154:0) after 1m0s
[06:41:44.237] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:154:0)
[06:41:44.237] Timer tick, asking Buildbucket for the build status
[06:41:46.486] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:46.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:155:0) after 1m0s
[06:42:46.532] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:155:0)
[06:42:46.532] Timer tick, asking Buildbucket for the build status
[06:42:49.050] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:49.050] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:156:0) after 1m0s
[06:43:49.101] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:156:0)
[06:43:49.101] Timer tick, asking Buildbucket for the build status
[06:43:49.640] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:49.640] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:157:0) after 1m0s
[06:44:49.648] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:157:0)
[06:44:49.648] Timer tick, asking Buildbucket for the build status
[06:44:49.763] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:49.763] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:158:0) after 1m0s
[06:45:49.875] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:158:0)
[06:45:49.875] Timer tick, asking Buildbucket for the build status
[06:45:49.984] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:49.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:159:0) after 1m0s
[06:46:50.169] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:159:0)
[06:46:50.169] Timer tick, asking Buildbucket for the build status
[06:46:50.286] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:50.286] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:160:0) after 1m0s
[06:47:50.410] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:160:0)
[06:47:50.410] Timer tick, asking Buildbucket for the build status
[06:47:50.957] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:50.957] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:161:0) after 1m0s
[06:48:51.073] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:161:0)
[06:48:51.073] Timer tick, asking Buildbucket for the build status
[06:48:51.226] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:51.226] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:162:0) after 1m0s
[06:49:51.262] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:162:0)
[06:49:51.262] Timer tick, asking Buildbucket for the build status
[06:49:51.379] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:51.379] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:163:0) after 1m0s
[06:50:51.371] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:163:0)
[06:50:51.371] Timer tick, asking Buildbucket for the build status
[06:50:51.503] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:51.503] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:164:0) after 1m0s
[06:51:51.640] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:164:0)
[06:51:51.640] Timer tick, asking Buildbucket for the build status
[06:51:51.845] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:51.845] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:165:0) after 1m0s
[06:52:51.896] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:165:0)
[06:52:51.896] Timer tick, asking Buildbucket for the build status
[06:52:52.008] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:52.008] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:166:0) after 1m0s
[06:53:52.039] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:166:0)
[06:53:52.039] Timer tick, asking Buildbucket for the build status
[06:53:52.863] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:52.863] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:167:0) after 1m0s
[06:54:52.937] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:167:0)
[06:54:52.937] Timer tick, asking Buildbucket for the build status
[06:54:53.168] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:53.168] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:168:0) after 1m0s
[06:55:53.189] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:168:0)
[06:55:53.189] Timer tick, asking Buildbucket for the build status
[06:55:53.312] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:53.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:169:0) after 1m0s
[06:56:53.303] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:169:0)
[06:56:53.303] Timer tick, asking Buildbucket for the build status
[06:56:53.476] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:53.476] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:170:0) after 1m0s
[06:57:53.499] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:170:0)
[06:57:53.499] Timer tick, asking Buildbucket for the build status
[06:57:53.647] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:53.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:171:0) after 1m0s
[06:58:53.675] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:171:0)
[06:58:53.675] Timer tick, asking Buildbucket for the build status
[06:58:55.430] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:55.430] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:172:0) after 1m0s
[06:59:55.457] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:172:0)
[06:59:55.457] Timer tick, asking Buildbucket for the build status
[06:59:55.569] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:55.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:173:0) after 1m0s
[07:00:55.558] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:173:0)
[07:00:55.558] Timer tick, asking Buildbucket for the build status
[07:00:55.944] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:55.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:174:0) after 1m0s
[07:01:55.965] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:174:0)
[07:01:55.965] Timer tick, asking Buildbucket for the build status
[07:01:56.105] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:56.105] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:175:0) after 1m0s
[07:02:56.282] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:175:0)
[07:02:56.282] Timer tick, asking Buildbucket for the build status
[07:02:56.573] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:56.573] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:176:0) after 1m0s
[07:03:56.789] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:176:0)
[07:03:56.789] Timer tick, asking Buildbucket for the build status
[07:03:57.187] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:57.187] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:177:0) after 1m0s
[07:04:57.250] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:177:0)
[07:04:57.250] Timer tick, asking Buildbucket for the build status
[07:04:57.438] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:57.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:178:0) after 1m0s
[07:05:57.481] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:178:0)
[07:05:57.481] Timer tick, asking Buildbucket for the build status
[07:05:57.599] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:57.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:179:0) after 1m0s
[07:06:57.624] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:179:0)
[07:06:57.624] Timer tick, asking Buildbucket for the build status
[07:06:57.709] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:57.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:180:0) after 1m0s
[07:07:57.734] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:180:0)
[07:07:57.734] Timer tick, asking Buildbucket for the build status
[07:07:57.948] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:57.948] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:181:0) after 1m0s
[07:08:57.956] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:181:0)
[07:08:57.956] Timer tick, asking Buildbucket for the build status
[07:08:58.334] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:58.334] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:182:0) after 1m0s
[07:09:58.394] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:182:0)
[07:09:58.394] Timer tick, asking Buildbucket for the build status
[07:09:58.555] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:58.556] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:183:0) after 1m0s
[07:10:58.667] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:183:0)
[07:10:58.667] Timer tick, asking Buildbucket for the build status
[07:10:58.804] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:58.804] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:184:0) after 1m0s
[07:11:58.809] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:184:0)
[07:11:58.809] Timer tick, asking Buildbucket for the build status
[07:11:58.936] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:58.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:185:0) after 1m0s
[07:12:58.973] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:185:0)
[07:12:58.973] Timer tick, asking Buildbucket for the build status
[07:12:59.096] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:59.096] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:186:0) after 1m0s
[07:13:59.168] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:186:0)
[07:13:59.168] Timer tick, asking Buildbucket for the build status
[07:13:59.233] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:59.233] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:187:0) after 1m0s
[07:14:59.215] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:187:0)
[07:14:59.215] Timer tick, asking Buildbucket for the build status
[07:14:59.398] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:59.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:188:0) after 1m0s
[07:15:59.419] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:188:0)
[07:15:59.419] Timer tick, asking Buildbucket for the build status
[07:15:59.759] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:59.759] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:189:0) after 1m0s
[07:16:59.782] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:189:0)
[07:16:59.782] Timer tick, asking Buildbucket for the build status
[07:16:59.912] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:59.912] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:190:0) after 1m0s
[07:17:59.942] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:190:0)
[07:17:59.942] Timer tick, asking Buildbucket for the build status
[07:18:00.079] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:00.079] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:191:0) after 1m0s
[07:19:00.103] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:191:0)
[07:19:00.103] Timer tick, asking Buildbucket for the build status
[07:19:00.293] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:00.293] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:192:0) after 1m0s
[07:20:00.365] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:192:0)
[07:20:00.365] Timer tick, asking Buildbucket for the build status
[07:20:00.446] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:00.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:193:0) after 1m0s
[07:21:00.486] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:193:0)
[07:21:00.486] Timer tick, asking Buildbucket for the build status
[07:21:00.581] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:00.581] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:194:0) after 1m0s
[07:22:00.571] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:194:0)
[07:22:00.571] Timer tick, asking Buildbucket for the build status
[07:22:00.716] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:00.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:195:0) after 1m0s
[07:23:00.735] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:195:0)
[07:23:00.735] Timer tick, asking Buildbucket for the build status
[07:23:01.074] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:01.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:196:0) after 1m0s
[07:24:01.154] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:196:0)
[07:24:01.154] Timer tick, asking Buildbucket for the build status
[07:24:01.342] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:01.342] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:197:0) after 1m0s
[07:25:01.419] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:197:0)
[07:25:01.419] Timer tick, asking Buildbucket for the build status
[07:25:01.604] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:01.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:198:0) after 1m0s
[07:26:01.668] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:198:0)
[07:26:01.668] Timer tick, asking Buildbucket for the build status
[07:26:01.766] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:01.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:199:0) after 1m0s
[07:27:01.902] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:199:0)
[07:27:01.902] Timer tick, asking Buildbucket for the build status
[07:27:02.014] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:02.014] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:200:0) after 1m0s
[07:28:02.055] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:200:0)
[07:28:02.055] Timer tick, asking Buildbucket for the build status
[07:28:08.571] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:08.571] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:201:0) after 1m0s
[07:29:08.633] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:201:0)
[07:29:08.633] Timer tick, asking Buildbucket for the build status
[07:29:09.981] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:09.981] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:202:0) after 1m0s
[07:30:10.037] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:202:0)
[07:30:10.037] Timer tick, asking Buildbucket for the build status
[07:30:10.340] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:10.340] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:203:0) after 1m0s
[07:31:10.381] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:203:0)
[07:31:10.381] Timer tick, asking Buildbucket for the build status
[07:31:10.492] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:10.492] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:204:0) after 1m0s
[07:32:10.615] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:204:0)
[07:32:10.615] Timer tick, asking Buildbucket for the build status
[07:32:10.762] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:10.762] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:205:0) after 1m0s
[07:33:10.788] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:205:0)
[07:33:10.788] Timer tick, asking Buildbucket for the build status
[07:33:11.065] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.065] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:206:0) after 1m0s
[07:34:11.131] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:206:0)
[07:34:11.131] Timer tick, asking Buildbucket for the build status
[07:34:11.296] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:11.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:207:0) after 1m0s
[07:35:11.464] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:207:0)
[07:35:11.464] Timer tick, asking Buildbucket for the build status
[07:35:12.040] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.040] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:208:0) after 1m0s
[07:36:12.136] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:208:0)
[07:36:12.136] Timer tick, asking Buildbucket for the build status
[07:36:12.261] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:12.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:209:0) after 1m0s
[07:37:12.298] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:209:0)
[07:37:12.298] Timer tick, asking Buildbucket for the build status
[07:37:12.444] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:12.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:210:0) after 1m0s
[07:38:12.531] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:210:0)
[07:38:12.531] Timer tick, asking Buildbucket for the build status
[07:38:12.808] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:12.808] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:211:0) after 1m0s
[07:39:12.807] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:211:0)
[07:39:12.807] Timer tick, asking Buildbucket for the build status
[07:39:12.951] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:12.952] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:212:0) after 1m0s
[07:40:12.981] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:212:0)
[07:40:12.981] Timer tick, asking Buildbucket for the build status
[07:40:15.598] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:15.598] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:213:0) after 1m0s
[07:41:15.622] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:213:0)
[07:41:15.622] Timer tick, asking Buildbucket for the build status
[07:41:15.805] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:214:0) after 1m0s
[07:42:15.844] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:214:0)
[07:42:15.844] Timer tick, asking Buildbucket for the build status
[07:42:16.255] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:16.255] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:215:0) after 1m0s
[07:43:16.292] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:215:0)
[07:43:16.292] Timer tick, asking Buildbucket for the build status
[07:43:16.444] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:16.444] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:216:0) after 1m0s
[07:44:16.504] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:216:0)
[07:44:16.504] Timer tick, asking Buildbucket for the build status
[07:44:16.721] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:16.721] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:217:0) after 1m0s
[07:45:16.747] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:217:0)
[07:45:16.747] Timer tick, asking Buildbucket for the build status
[07:45:17.044] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:17.044] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:218:0) after 1m0s
[07:46:17.049] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:218:0)
[07:46:17.049] Timer tick, asking Buildbucket for the build status
[07:46:17.197] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:17.197] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:219:0) after 1m0s
[07:47:17.211] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:219:0)
[07:47:17.211] Timer tick, asking Buildbucket for the build status
[07:47:17.321] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:17.321] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:220:0) after 1m0s
[07:48:17.443] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:220:0)
[07:48:17.443] Timer tick, asking Buildbucket for the build status
[07:48:18.861] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:18.861] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:221:0) after 1m0s
[07:49:18.920] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:221:0)
[07:49:18.920] Timer tick, asking Buildbucket for the build status
[07:49:19.667] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:19.667] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:222:0) after 1m0s
[07:50:19.696] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:222:0)
[07:50:19.696] Timer tick, asking Buildbucket for the build status
[07:50:19.881] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:223:0) after 1m0s
[07:51:19.948] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:223:0)
[07:51:19.948] Timer tick, asking Buildbucket for the build status
[07:51:20.101] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:20.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:224:0) after 1m0s
[07:52:20.146] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:224:0)
[07:52:20.146] Timer tick, asking Buildbucket for the build status
[07:52:20.276] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.276] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:225:0) after 1m0s
[07:53:20.357] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:225:0)
[07:53:20.357] Timer tick, asking Buildbucket for the build status
[07:53:21.552] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:21.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:226:0) after 1m0s
[07:54:21.570] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:226:0)
[07:54:21.570] Timer tick, asking Buildbucket for the build status
[07:54:21.670] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:21.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:227:0) after 1m0s
[07:55:21.659] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:227:0)
[07:55:21.659] Timer tick, asking Buildbucket for the build status
[07:55:21.985] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:21.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:228:0) after 1m0s
[07:56:22.072] Handling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:228:0)
[07:56:22.072] Timer tick, asking Buildbucket for the build status
[07:56:23.098] Build 8884976710561874224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:23.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-64-validator-opt:9050406772586778608:229:0) after 1m0s
[07:56:48.491] Received PubSub notification, asking Buildbucket for the build status
[07:56:48.571] Build 8884976710561874224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:56:48.572] Buildbucket build:
{
  "build": {
    "bucket": "luci.nacl.ci",
    "canary_preference": "PROD",
    "completed_ts": "1585036607585798",
    "created_by": "project:nacl",
    "created_ts": "1585022931477046",
    "failure_reason": "BUILD_FAILURE",
    "id": "8884976710561874224",
    "parameters_json": "{\"builder_name\": \"linux-64-validator-opt\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}}",
    "project": "nacl",
    "result": "FAILURE",
    "result_details_json": "{\"error\": {\"message\": \"Step('annotated steps') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"title\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/ffed96d1528df9f114a30b14d4eb06120c8fb022\"}]}, \"bot_id\": \"luci-flex-ci-xenial-11-bd29\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-64-validator-opt\", \"buildnumber\": 129, \"got_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"mastername\": \"client.nacl\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_e0a23fe50c3a00b2c15d28f88e53be240e9f7a6cf6b454bad2e93c66a79c760c_v2\", \"builder_ef4359b44e8e6f795ffeafdf2e7f742ba75a6a61dcb0f4f425c29b4ccf5d74d9_v2\", \"builder_faaedc22e3ac6fec098652e7f0fd10d4f879f8f9ad55ea8ef979585009ee36da_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-flex-ci-xenial-11-bd29\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4895-ce889b2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('annotated steps') (retcode: 1)\"}}",
    "service_account": "nacl-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1585022946957627",
    "status": "COMPLETED",
    "status_changed_ts": "1585022947696280",
    "tags": [
      "build_address:luci.nacl.ci/linux-64-validator-opt/129",
      "builder:linux-64-validator-opt",
      "buildset:commit/git/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/ffed96d1528df9f114a30b14d4eb06120c8fb022",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9050406772586778608",
      "scheduler_job_id:nacl/linux-64-validator-opt",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8884976710561874224/+/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:4b238c398692f210",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1585036607660143",
    "url": "https://ci.chromium.org/b/8884976710561874224",
    "utcnow_ts": "1585036608563578"
  }
}
[07:56:48.573] Invocation finished in 3h47m58.410913251s with status FAILED