juju-core

Juju2 with MAAS2, log shows errors after having created a controller

Bug #1592179 reported by Peter Matulis on 2016-06-13

This bug affects 1 person

Affects		Status	Importance	Assigned to	Milestone
	juju-core	Expired	Undecided	Unassigned

Bug Description

On a fresh Xenial system I am using Juju 2.0 and MAAS 2.0. After creating a controller, which seemed to go fine, I am getting errors in the logs. Both Juju and MAAS are on the same host. The MAAS nodes (the one used for the controller) is a KVM guest on another system.

Juju client: 192.168.1.5
MAAS server: 192.168.1.5
Juju controller: 192.168.1.64

$ export JUJU_DEV_FEATURE_FLAGS=maas2
$ juju bootstrap --bootstrap-constraints arch=amd64 maas-controller golem-maas

$ juju list-controllers

CONTROLLER MODEL USER CLOUD/REGION
local.maas-controller* controller admin@local golem-maas

$ juju show-controller maas-controller
====================================
maas-controller:
  details:
    uuid: b3073ded-8221-4d42-852f-50c69234dcf8
    api-endpoints: ['192.168.1.64:17070']
    ca-cert: |
      -----BEGIN CERTIFICATE-----
      MIID0zCCArugAwIBAgIUCCa6FtYBzQrgSLIkbUYqh1pUXTIwDQYJKoZIhvcNAQEL
      BQAwcTENMAsGA1UEChMEanVqdTExMC8GA1UEAwwoanVqdS1nZW5lcmF0ZWQgQ0Eg
      Zm9yIG1vZGVsICJjb250cm9sbGVyIjEtMCsGA1UEBRMkYjMwNzNkZWQtODIyMS00
      ZDQyLTg1MmYtNTBjNjkyMzRkY2Y4MB4XDTE2MDYwNjIwMzIxMFoXDTI2MDYxMzIw
      MzIwOVowcTENMAsGA1UEChMEanVqdTExMC8GA1UEAwwoanVqdS1nZW5lcmF0ZWQg
      Q0EgZm9yIG1vZGVsICJjb250cm9sbGVyIjEtMCsGA1UEBRMkYjMwNzNkZWQtODIy
      MS00ZDQyLTg1MmYtNTBjNjkyMzRkY2Y4MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8A
      MIIBCgKCAQEAqIIcvIKGeai3Pq9Wy31zbrVGKae+tZTYw6CQk8RIyVdNnxewodjc
      fg+fiZaZEWknqJR26bQLsKccjVVpyJzG/HyRAK4N6m6JfSnwRvhS2EYrgFIsaIvx
      fuA8oU+57F5QgjO19NfGs7Rhn5PikacByaZXHbkTi6LfVtKTmpEkvnA/zgUl+CIQ
      I9MoKXAVf6LGt17kOFbU71dRJG5zQ0AR8bP+UAkBeTF0RQjRlPnQV3A/GvI6GTyi
      rZn/XxYMVKFYHlrMTAAeXPTq+rK5UHYNzaQlGvsGyjbA3iqEKwSeNyrL0MajkKSr
      snoPOOF/2OcHS/g7DaiyiY099AcNMQ9/mwIDAQABo2MwYTAOBgNVHQ8BAf8EBAMC
      AqQwDwYDVR0TAQH/BAUwAwEB/zAdBgNVHQ4EFgQUy0vGEvcUwVMD5dHRPaxYVhIW
      i+AwHwYDVR0jBBgwFoAUy0vGEvcUwVMD5dHRPaxYVhIWi+AwDQYJKoZIhvcNAQEL
      BQADggEBAHr+Uy+UACWYzs/PsgPSsHWnSd2pcPXyffqC2huIsHHQxSV+k0OajQnh
      zK3Ec1U7SljP81aG0ebWl9A473VrIh5mwk28p6t4nysqgLYeOoWPtQLnn24wfZxJ
      PQMH8ThVxVtvVznODITSgSFFSMD6FPJEksEFpGg841a/oHug4o54yMGc1aArWdye
      KIbnuOpq/MdOBMdbYnqpojqDzzdMXAOURbQ0/0FQdi8RLRDKiGZwyL1oVskEtXmM
      NrZju2r6i5u/m7HhvOL4+glMZzbyBQgyIk5rEF41/LhR/NjrJYoGJvfnNMNsY8KJ
      3mOwVK55Fap6Qa550bS6Wv9ZjR5puic=
      -----END CERTIFICATE-----
    cloud: golem-maas
  accounts:
    admin@local:
      user: admin@local
      models:
        controller:
          uuid: b3073ded-8221-4d42-852f-50c69234dcf8
        default:
          uuid: ff5a5833-6979-4129-849b-c0b9d6b03892
      current-model: controller
  current-account: admin@local
  bootstrap-config:
    cloud: golem-maas
    cloud-type: maas
    endpoint: http://192.168.1.5/MAAS
    credential: golem
====================================

$ juju switch

local.maas-controller:controller

$ juju debug-log --replay -T -l TRACE | grep ERROR

machine-0: 2016-06-13 20:42:14 ERROR juju.worker.dependency engine.go:526 "api-caller" manifold worker returned unexpected error: cannot open api: unable to connect to API: websocket.Dial wss://localhost:17070/mo
del/b3073ded-8221-4d42-852f-50c69234dcf8/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.worker.logger logger.go:47 connection is shut down
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:18 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:42:21 ERROR juju.rpc server.go:577 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:45216: write: connection reset by peer
machine-0: 2016-06-13 20:54:12 ERROR juju.apiserver.presence.machine-0 pinger.go:207 pinger failed: E11000 duplicate key error collection: presence.presence.pings index: _id_ dup key: { : "b3073ded-8221-4d42-852f
-50c69234dcf8:1465851240" }

See original description

Peter Matulis (petermatulis) on 2016-06-13

description:

updated

Revision history for this message

Peter Matulis (petermatulis) wrote on 2016-06-13:

Here is /var/log/syslog from the controller:

http://paste.ubuntu.com/17311172/

Revision history for this message

Cheryl Jennings (cherylj) wrote on 2016-06-14:

There wasn't anything that stood out in the syslog. Do you have the complete machine-0.log from the controller?

Changed in juju-core:
status:	New → Incomplete

Revision history for this message

Launchpad Janitor (janitor) wrote on 2016-08-14:

[Expired for juju-core because there has been no activity for 60 days.]

Changed in juju-core:
status:	Incomplete → Expired

Revision history for this message

Asad Saeeduddin (masaeedu) wrote on 2017-02-01:

Download full text (94.3 KiB)

Still seeing this with:
- juju 2.0.0-xenial-amd64
- lxd 2.0.8
- Xenial 16.04.1 LTS

On the controller, /var/log/juju/machine-0.log appears to have the same contents as the juju debug-log output described in the issue, but I'm reproducing the contents here nevertheless:

Still seeing this with:
 - juju 2.0.0-xenial-amd64
 - lxd 2.0.8
 - Xenial 16.04.1 LTS

On the controller, /var/log/juju/machine-0.log appears to have the same contents as the juju debug-log output described in the issue, but I'm reproducing the contents here nevertheless:

ubuntu@juju-8c5551-0:~$ sudo cat /var/log/juju/machine-0.log
2017-02-01 01:58:15 INFO juju.cmd supercommand.go:63 running jujud [2.0.0 gc go1.6.2]
2017-02-01 01:58:15 DEBUG juju.cmd supercommand.go:64   args: []string{"/var/lib/juju/tools/machine-0/jujud", "machine", "--data-dir", "/var/lib/juju", "--machine-id", "0", "--debug"}
2017-02-01 01:58:15 DEBUG juju.agent agent.go:509 read agent config, format "2.0"
2017-02-01 01:58:15 INFO juju.cmd.jujud machine.go:404 machine agent machine-0 start (2.0.0 [gc])
2017-02-01 01:58:16 DEBUG juju.wrench wrench.go:112 couldn't read wrench directory: stat /var/lib/juju/wrench: no such file or directory
2017-02-01 01:58:16 INFO juju.worker.upgradesteps worker.go:72 no upgrade steps required or upgrade steps for 2.0.0 have already been run.
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "engine"
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "tools-version-checker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "machine-action-runner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "termination-signal-handler" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "state-config-watcher" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "upgrade-check-gate" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-config-watcher" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "proxy-config-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "reboot-executor" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "disk-manager" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-address-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "machiner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "ssh-identity-writer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "agent" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "state" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "migration-minion" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.introspection socket.go:72 introspection worker listening on "@jujud-machine-0"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "engine" started
2017-02-01 01:58:16 DEBUG juju.worker.introspection socket.go:96 stats worker now servering
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "serving-info-setter" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "unit-agent-deployer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "logging-config-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "mgo-txn-resumer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "host-key-reporter" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "unconverted-state-workers" manifold worker stopped: "state" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.apicaller connect.go:99 connecting with current password
2017-02-01 01:58:16 INFO juju.api apiclient.go:519 dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "upgrade-steps-gate" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "upgrade-steps-runner" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "unconverted-api-workers" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "log-sender" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "upgrade-check-flag" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "upgrade-steps-flag" manifold worker stopped: "upgrade-steps-gate" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "upgrader" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "upgrade-check-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "ssh-authkeys-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 INFO juju.api apiclient.go:528 error dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api": websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 DEBUG juju.worker.apicaller connect.go:139 failed to connect
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-caller" manifold worker stopped: cannot open api: unable to connect to API: websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 ERROR juju.worker.dependency engine.go:539 "api-caller" manifold worker returned unexpected error: cannot open api: unable to connect to API: websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "ssh-identity-writer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "tools-version-checker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "machine-action-runner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "state-config-watcher" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "state" manifold worker stopped: <nil>
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "proxy-config-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "api-config-watcher" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "reboot-executor" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "disk-manager" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-address-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "machiner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-caller" manifold worker stopped: <nil>
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "upgrader" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "upgrade-steps-runner" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not set: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "upgrade-steps-flag" manifold worker started
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: <nil>
2017-02-01 01:58:16 DEBUG juju.worker.apicaller connect.go:99 connecting with current password
2017-02-01 01:58:16 INFO juju.api apiclient.go:519 dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:16 INFO juju.api apiclient.go:528 error dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api": websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 DEBUG juju.service discovery.go:112 discovered init system "systemd" from local host
2017-02-01 01:58:16 DEBUG juju.worker.apicaller connect.go:139 failed to connect
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "api-caller" manifold worker stopped: cannot open api: unable to connect to API: websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 ERROR juju.worker.dependency engine.go:539 "api-caller" manifold worker returned unexpected error: cannot open api: unable to connect to API: websocket.Dial wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api: dial tcp 127.0.0.1:17070: getsockopt: connection refused
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not set: dependency not available
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "upgrade-check-flag" not set: dependency not available
2017-02-01 01:58:16 DEBUG juju.cmd.jujud machine.go:1303 mongodb service is installed
2017-02-01 01:58:16 INFO juju.state open.go:69 opening state, mongo addresses: ["127.0.0.1:37017"]; entity machine-0
2017-02-01 01:58:16 DEBUG juju.state open.go:70 dialing mongo
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:16 DEBUG juju.state open.go:75 connection established
2017-02-01 01:58:16 DEBUG juju.state open.go:82 mongodb login successful
2017-02-01 01:58:16 INFO juju.state state.go:354 starting standard state workers
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:53 starting leadership lease manager
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:59 starting singular lease manager
2017-02-01 01:58:16 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:16.232228611 +0000 UTC
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:65 starting transaction log watcher
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:71 starting presence watcher
2017-02-01 01:58:16 INFO juju.state state.go:370 creating cloud image metadata storage
2017-02-01 01:58:16 INFO juju.state state.go:376 started state for model-fb0f8b26-20d8-46ad-850f-0e846d8c5551 successfully
2017-02-01 01:58:16 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:16.234068553 +0000 UTC
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "state" manifold worker started
2017-02-01 01:58:16 INFO juju.worker.singular singular.go:57 runner created; isMaster true
2017-02-01 01:58:16 DEBUG juju.utils gomaxprocs.go:24 setting GOMAXPROCS to 1
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "peergrouper"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "peergrouper" started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "restore"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "restore" started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "mongoupgrade"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "mongoupgrade" started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "apiserver"
2017-02-01 01:58:16 DEBUG juju.cmd.jujud machine.go:964 opening state for apiserver worker
2017-02-01 01:58:16 INFO juju.state open.go:69 opening state, mongo addresses: ["127.0.0.1:37017"]; entity machine-0
2017-02-01 01:58:16 DEBUG juju.state open.go:70 dialing mongo
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "model worker manager"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "model worker manager" started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "certupdater"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "certupdater" started
2017-02-01 01:58:16 INFO juju.worker.singular singular.go:100 starting "dblogpruner"
2017-02-01 01:58:16 INFO juju.worker.singular singular.go:100 starting "txnpruner"
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:478 "unconverted-state-workers" manifold worker started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "dblogpruner"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "dblogpruner" started
2017-02-01 01:58:16 INFO juju.worker runner.go:256 start "txnpruner"
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "txnpruner" started
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:16 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not set: dependency not available
2017-02-01 01:58:16 DEBUG juju.state open.go:75 connection established
2017-02-01 01:58:16 DEBUG juju.state open.go:82 mongodb login successful
2017-02-01 01:58:16 INFO juju.state state.go:354 starting standard state workers
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:53 starting leadership lease manager
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:59 starting singular lease manager
2017-02-01 01:58:16 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:16.28521202 +0000 UTC
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:65 starting transaction log watcher
2017-02-01 01:58:16 DEBUG juju.state.workers dumb.go:71 starting presence watcher
2017-02-01 01:58:16 INFO juju.state state.go:370 creating cloud image metadata storage
2017-02-01 01:58:16 INFO juju.state state.go:376 started state for model-fb0f8b26-20d8-46ad-850f-0e846d8c5551 successfully
2017-02-01 01:58:16 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:16.285994538 +0000 UTC
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:16 INFO juju.apiserver apiserver.go:684 new certificate addresses: 
2017-02-01 01:58:16 DEBUG juju.worker runner.go:183 "apiserver" started
2017-02-01 01:58:16 INFO juju.apiserver apiserver.go:265 listening on "[::]:17070"
2017-02-01 01:58:16 DEBUG juju.apiserver apiserver.go:315 Starting API http server on address "[::]:17070"
2017-02-01 01:58:16 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:17 DEBUG juju.apiserver request_notifier.go:70 [1] API connection from 10.101.229.1:55252
2017-02-01 01:58:17 INFO juju.state state.go:354 starting standard state workers
2017-02-01 01:58:17 DEBUG juju.state.workers dumb.go:53 starting leadership lease manager
2017-02-01 01:58:17 DEBUG juju.state.workers dumb.go:59 starting singular lease manager
2017-02-01 01:58:17 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:17.879858611 +0000 UTC
2017-02-01 01:58:17 DEBUG juju.state.workers dumb.go:65 starting transaction log watcher
2017-02-01 01:58:17 DEBUG juju.state.workers dumb.go:71 starting presence watcher
2017-02-01 01:58:17 INFO juju.state state.go:370 creating cloud image metadata storage
2017-02-01 01:58:17 INFO juju.state state.go:376 started state for model-2c3090ee-3d0a-46df-8106-9d865f2b721b successfully
2017-02-01 01:58:17 DEBUG juju.apiserver request_notifier.go:115 <- [1]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:17 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:17.880308421 +0000 UTC
2017-02-01 01:58:17 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:18 DEBUG juju.apiserver admin.go:258 model login: user admin has "superuser" for controller; "admin" for model 2c3090ee-3d0a-46df-8106-9d865f2b721b
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:140 -> [1]  165.14417ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:115 <- [1] user-admin {"request-id":2,"type":"Block","version":2,"request":"List","params":"'params redacted'"}
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:140 -> [1] user-admin 53.901µs {"request-id":2,"error":"upgrade in progress","error-code":"upgrade in progress","response":"'body redacted'"} Block[""].List
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:80 [1] user-admin API connection terminated after 170.266788ms
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:70 [2] API connection from 10.101.229.1:55256
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:115 <- [2]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:18 DEBUG juju.apiserver admin.go:258 model login: user admin has "superuser" for controller; "admin" for model 2c3090ee-3d0a-46df-8106-9d865f2b721b
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:140 -> [2]  22.35731ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:115 <- [2] user-admin {"request-id":2,"type":"Block","version":2,"request":"List","params":"'params redacted'"}
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:140 -> [2] user-admin 55.301µs {"request-id":2,"error":"upgrade in progress","error-code":"upgrade in progress","response":"'body redacted'"} Block[""].List
2017-02-01 01:58:18 DEBUG juju.apiserver request_notifier.go:80 [2] user-admin API connection terminated after 33.168257ms
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:70 [3] API connection from 10.101.229.1:55258
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [3]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver admin.go:258 model login: user admin has "superuser" for controller; "admin" for model 2c3090ee-3d0a-46df-8106-9d865f2b721b
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [3]  30.078087ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [3] user-admin {"request-id":2,"type":"Block","version":2,"request":"List","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [3] user-admin 88.402µs {"request-id":2,"error":"upgrade in progress","error-code":"upgrade in progress","response":"'body redacted'"} Block[""].List
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:80 [3] user-admin API connection terminated after 32.84235ms
2017-02-01 01:58:19 DEBUG juju.worker.apicaller connect.go:99 connecting with current password
2017-02-01 01:58:19 INFO juju.api apiclient.go:519 dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:70 [4] API connection from 127.0.0.1:37364
2017-02-01 01:58:19 INFO juju.api apiclient.go:309 connection established to "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver admin.go:164 model login: machine-0 for fb0f8b26-20d8-46ad-850f-0e846d8c5551
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4]  209.441888ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:19 DEBUG juju.worker.apicaller connect.go:142 connected
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":2,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 738.917µs {"request-id":2,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":3,"type":"Agent","version":2,"request":"SetPasswords","params":"'params redacted'"}
2017-02-01 01:58:19 INFO juju.apiserver.common password.go:74 setting mongo password for "machine-0"
2017-02-01 01:58:19 INFO juju.apiserver.common password.go:105 setting password for "machine-0"
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 74.542304ms {"request-id":3,"response":"'body redacted'"} Agent[""].SetPasswords
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "api-caller" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "ssh-authkeys-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "tools-version-checker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machine-action-runner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "proxy-config-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "reboot-executor" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "disk-manager" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not set: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-address-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machiner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "ssh-identity-writer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-minion" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "serving-info-setter" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unit-agent-deployer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "logging-config-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "mgo-txn-resumer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "host-key-reporter" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unconverted-api-workers" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "log-sender" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "upgrader" manifold worker started
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":4,"type":"MigrationFlag","version":1,"request":"Phase","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":5,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":6,"type":"Upgrader","version":1,"request":"SetTools","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 3.024669ms {"request-id":5,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":7,"type":"Machiner","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 1.951244ms {"request-id":7,"response":"'body redacted'"} Machiner[""].Life
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "upgrade-steps-runner" manifold worker started
2017-02-01 01:58:19 DEBUG juju.wrench wrench.go:112 couldn't read wrench directory: stat /var/lib/juju/wrench: no such file or directory
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "upgrade-steps-runner" manifold worker stopped: <nil>
2017-02-01 01:58:19 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 31.912329ms {"request-id":4,"response":"'body redacted'"} MigrationFlag[""].Phase
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "migration-inactive-flag" manifold worker started
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":8,"type":"MigrationFlag","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 189.204µs {"request-id":8,"response":"'body redacted'"} MigrationFlag[""].Watch
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":9,"type":"MigrationFlag","version":1,"request":"Phase","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":10,"type":"NotifyWatcher","version":1,"id":"2","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 1.098525ms {"request-id":9,"response":"'body redacted'"} MigrationFlag[""].Phase
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "ssh-identity-writer" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "tools-version-checker" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machine-action-runner" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "proxy-config-updater" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "reboot-executor" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "disk-manager" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-address-updater" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machiner" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "ssh-authkeys-updater" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "serving-info-setter" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unit-agent-deployer" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "logging-config-updater" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "mgo-txn-resumer" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "host-key-reporter" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unconverted-api-workers" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "log-sender" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 283.136173ms {"request-id":6,"response":"'body redacted'"} Upgrader[""].SetTools
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":11,"type":"Upgrader","version":1,"request":"WatchAPIVersion","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 530.512µs {"request-id":11,"response":"'body redacted'"} Upgrader[""].WatchAPIVersion
2017-02-01 01:58:19 INFO juju.worker.upgrader upgrader.go:139 abort check blocked until version event received
2017-02-01 01:58:19 INFO juju.worker.upgrader upgrader.go:145 unblocking abort check
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":12,"type":"Upgrader","version":1,"request":"DesiredVersion","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":13,"type":"NotifyWatcher","version":1,"id":"3","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 449.31µs {"request-id":12,"response":"'body redacted'"} Upgrader[""].DesiredVersion
2017-02-01 01:58:19 INFO juju.worker.upgrader upgrader.go:178 desired tool version: 2.0.0
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "txnpruner"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "upgrade-check-flag" manifold worker stopped: gate unlocked
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "peergrouper"
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "restore"
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "mongoupgrade"
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "model worker manager"
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "certupdater"
2017-02-01 01:58:19 DEBUG juju.cmd.jujud machine.go:1385 upgrades done, starting worker "dblogpruner"
2017-02-01 01:58:19 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "log-forwarder" manifold worker stopped: "upgrade-check-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "upgrade-check-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "upgrade-check-flag" manifold worker started
2017-02-01 01:58:19 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:19 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:19 INFO juju.worker runner.go:256 start "fb0f8b26-20d8-46ad-850f-0e846d8c5551"
2017-02-01 01:58:19 DEBUG juju.workers.modelworkermanager modelworkermanager.go:129 starting workers for model "fb0f8b26-20d8-46ad-850f-0e846d8c5551"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "migration-fortress" manifold worker started
2017-02-01 01:58:19 INFO juju.worker runner.go:256 start "2c3090ee-3d0a-46df-8106-9d865f2b721b"
2017-02-01 01:58:19 DEBUG juju.workers.modelworkermanager modelworkermanager.go:129 starting workers for model "2c3090ee-3d0a-46df-8106-9d865f2b721b"
2017-02-01 01:58:19 DEBUG juju.worker.certupdater certupdater.go:118 new machine addresses: []network.Address{local-cloud:10.101.229.96}
2017-02-01 01:58:19 DEBUG juju.worker runner.go:183 "fb0f8b26-20d8-46ad-850f-0e846d8c5551" started
2017-02-01 01:58:19 DEBUG juju.worker runner.go:183 "2c3090ee-3d0a-46df-8106-9d865f2b721b" started
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":14,"type":"Agent","version":2,"request":"ControllerConfig","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.tools.lxdclient client.go:199 connecting to LXD remote "remote": "10.101.229.1:8443"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "not-dead-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "environ-tracker" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "firewaller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "application-scaler" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "status-history-pruner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "clock" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "spaces-imported-gate" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "state-cleaner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machine-undertaker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-config-watcher" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "metric-worker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "state-cleaner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "not-dead-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "not-alive-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "agent" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "clock" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "spaces-imported-gate" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "environ-tracker" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-config-watcher" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "status-history-pruner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "machine-undertaker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unit-assigner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "application-scaler" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-caller" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "is-responsible-flag" manifold worker stopped: "clock" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "firewaller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "agent" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "api-caller" manifold worker stopped: "agent" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "not-alive-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "is-responsible-flag" manifold worker stopped: "clock" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "unit-assigner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "metric-worker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 22.515514ms {"request-id":14,"response":"'body redacted'"} Agent[""].ControllerConfig
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "migration-minion" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.certupdater certupdater.go:189 existing cert addresses map[]
2017-02-01 01:58:19 DEBUG juju.worker.certupdater certupdater.go:190 new addresses [localhost juju-apiserver juju-mongodb anything 10.101.229.96]
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "log-forwarder" manifold worker started
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":15,"type":"MigrationMinion","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 117.002µs {"request-id":15,"response":"'body redacted'"} MigrationMinion[""].Watch
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "api-config-watcher" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.apicaller connect.go:99 connecting with current password
2017-02-01 01:58:19 INFO juju.api apiclient.go:519 dialing "wss://localhost:17070/model/2c3090ee-3d0a-46df-8106-9d865f2b721b/api"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "is-responsible-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.apicaller connect.go:99 connecting with current password
2017-02-01 01:58:19 INFO juju.api apiclient.go:519 dialing "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:478 "api-config-watcher" manifold worker started
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "is-responsible-flag" manifold worker stopped: "api-caller" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":16,"type":"Agent","version":2,"request":"WatchForModelConfigChanges","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":17,"type":"MigrationStatusWatcher","version":1,"id":"4","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:19 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 31.02701ms {"request-id":16,"response":"'body redacted'"} Agent[""].WatchForModelConfigChanges
2017-02-01 01:58:19 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":{"api_extensions":["id_map"],"api_status":"stable","api_version":"1.0","auth":"trusted","config":{"core.https_address":"[::]","storage.zfs_pool_name":"lxd"},"environment":{"addresses":["172.16.144.97:8443","10.101.229.1:8443"],"architectures":["x86_64","i686"],"certificate":"-----BEGIN CERTIFICATE-----\nMIIFWTCCA0GgAwIBAgIRAPchIDsBRbhRkmXzo12AHDswDQYJKoZIhvcNAQELBQAw\nPDEcMBoGA1UEChMTbGludXhjb250YWluZXJzLm9yZzEcMBoGA1UEAwwTcm9vdEBh\nc2FkLXVidW50dS0xNjAeFw0xNzAyMDEwMTI1MzFaFw0yNzAxMzAwMTI1MzFaMDwx\nHDAaBgNVBAoTE2xpbnV4Y29udGFpbmVycy5vcmcxHDAaBgNVBAMME3Jvb3RAYXNh\nZC11YnVudHUtMTYwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCix7my\nM4fGYRsoUiialbS5OsHICp4vtDIeJv4S/OA2FqUDhjH736sI4UR5FeC2UK8cQ73c\ns92TxUWMWrGCkQsq+k7yRYuvPyVXaSMqhmT62MlvQBFSMK4nxTEIN13raJsZ4dKj\n2PwR+VaRfJu9Z7H2uamNvcp6mSEH2yJ7Wc479c593fxv+gbq30Fpv3zO0yqyo1wc\nW0B0ML7vqCUr8IVkwO/1Bkn5vqAM9LOqWCu0i6kMnHXrBI3LvnNjVaUhyuRKHJKP\nZX97g1xU/npYd7ec0fHibaUb2fWvKoMIGvROKK3LzaCZj6yoVAhD48dg8lZFWXmv\ny8yg2KjHQM3ddfjuFafa5ea0YOUbXgsNMQjUp2pQu11ArPqyXwKglVwS10FgARd1\nI8eMtZZ5H/ZE8uwiO3s3x5/MUEKtEuXm5dUtE7L4ZyNW+7wX4fexJnxqa6flejfY\nwezAdBYZMY6QtvnkxjzcBZUjZ67cAnDNgbehxN12XlGcZUUhhW62TgfAIAvNNjIX\nuYSLUvvD4wnM3pgU8P5JzfXsgu9gqllL70x3HbC6WTjK9jj1bH8V5EpimBC2OtkV\nTEiPg1VOml8enq3pCan8gyQHufReJcq29jWnlRs1cWUCQp6sPWnke0CyMrr/bOeQ\nwD0kYqCxouU+m8g6tlsB9cgkMtJ++kMVoFfzOQIDAQABo1YwVDAOBgNVHQ8BAf8E\nBAMCBaAwEwYDVR0lBAwwCgYIKwYBBQUHAwEwDAYDVR0TAQH/BAIwADAfBgNVHREE\nGDAWgg5hc2FkLXVidW50dS0xNocErBCQYTANBgkqhkiG9w0BAQsFAAOCAgEAO2eF\nxwwayFySu87YjZDF8LN03H83/huN2IMCsIGjNGAocP8DGO71opQZLhTQU4IELxfc\nc8jmnbS96IqhL/8YilfKS+NiPBqdpPs1yAjPkiieoFN0SxG3vWM/oaKrDlGu0yhi\nfLg22b51UphhdWFYcwqB4MfzZyRIpgL/Ptgdf9hV0Pgm8gvgD3j5+1TYZ2CJX4WN\neNrhHU3DLqZtR2ffVpoTFM6rncp9GUKYlkAgVelbEoqmQqEFIYiuI+rCmRnlbJFB\naZ+yg3aMJ9Qu/IZVfuEE4mQX0NkxVB+Oo5a9RuBCppw5iO1fJQhKrFKq3wXea7BR\nfsT8kDleucS3VMDkZAPEB24HDxt4kDJ5imqVtaARkg3K+pDzj+M0Flw+7/YSP+Nb\n3Gj179NrT0kz5PZleWOZJQXVFzbIPzjushnFojXXue+QINhQzXAwY1yBtPDf0YrW\nPAD55HLmZYfzqUtGelfnDbUFD8U2VUp31ZsNsgZAgSWTiqH2hkGfc6ZI+Q8Wzzmr\n/xvikE1n9ZdR7lRPKo/RrDZt6vKermh1ELE3Ku9HsFzIj1E9VZVgB+vtqXF1qQwK\nv/bvKZf1u86w3P6BDHuFemfLLw9QoBMnNWHmXcwQgwhEAl5q18Y9advH3u2+1Woo\nmZqFaraseYVPsaG+AsLZ+DXrhmvywFFF9vVrLTI=\n-----END CERTIFICATE-----\n","driver":"lxc","driver_version":"2.0.6","kernel":"Linux","kernel_architecture":"x86_64","kernel_version":"4.4.0-31-generic","server":"lxd","server_pid":17652,"server_version":"2.0.8","storage":"zfs","storage_version":"5"},"public":false}}
2017-02-01 01:58:19 INFO juju.tools.lxdclient client.go:247 using LXD API version "1.0"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 26.038095ms {"request-id":17,"response":"'body redacted'"} MigrationStatusWatcher["4"].Next
2017-02-01 01:58:20 INFO juju.worker.migrationminion worker.go:116 migration phase is now: NONE
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "ssh-authkeys-updater" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "storage-provisioner" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "machine-action-runner" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "proxy-config-updater" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "reboot-executor" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "disk-manager" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "api-address-updater" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.logger logger.go:34 initial log config: "<root>=DEBUG"
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "logging-config-updater" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "host-key-reporter" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.logger logger.go:65 logger setup
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "log-sender" manifold worker started
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":18,"type":"Agent","version":2,"request":"ModelConfig","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":19,"type":"NotifyWatcher","version":1,"id":"5","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":20,"type":"MigrationStatusWatcher","version":1,"id":"4","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":21,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":22,"type":"Agent","version":2,"request":"ModelConfig","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":23,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":24,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":25,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":26,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":27,"type":"Agent","version":2,"request":"GetEntities","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":28,"type":"KeyUpdater","version":1,"request":"AuthorisedKeys","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":29,"type":"StorageProvisioner","version":3,"request":"WatchBlockDevices","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":30,"type":"MachineActions","version":1,"request":"RunningActions","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":31,"type":"ProxyUpdater","version":1,"request":"ProxyConfig","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":32,"type":"Reboot","version":2,"request":"WatchForRebootEvent","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":33,"type":"Machiner","version":1,"request":"WatchAPIHostPorts","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":34,"type":"Logger","version":1,"request":"LoggingConfig","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":35,"type":"HostKeyReporter","version":1,"request":"ReportKeys","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:70 [5] API connection from 127.0.0.1:37378
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:70 [6] API connection from 127.0.0.1:37376
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:70 [7] API connection from 10.101.229.1:55278
2017-02-01 01:58:20 INFO juju.api apiclient.go:309 connection established to "wss://localhost:17070/model/fb0f8b26-20d8-46ad-850f-0e846d8c5551/api"
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 142.569861ms {"request-id":27,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 68.350863ms {"request-id":21,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 74.130296ms {"request-id":18,"response":"'body redacted'"} Agent[""].ModelConfig
2017-02-01 01:58:20 INFO juju.api apiclient.go:309 connection established to "wss://localhost:17070/model/2c3090ee-3d0a-46df-8106-9d865f2b721b/api"
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [7]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 163.635043ms {"request-id":26,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 175.416213ms {"request-id":23,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 185.518244ms {"request-id":24,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "10.101.229.96:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 197.478017ms {"request-id":25,"response":"'body redacted'"} Agent[""].GetEntities
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 67.901953ms {"request-id":22,"response":"'body redacted'"} Agent[""].ModelConfig
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 220.431743ms {"request-id":28,"response":"'body redacted'"} KeyUpdater[""].AuthorisedKeys
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6]  {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":{"api_extensions":["id_map"],"api_status":"stable","api_version":"1.0","auth":"trusted","config":{"core.https_address":"[::]","storage.zfs_pool_name":"lxd"},"environment":{"addresses":["172.16.144.97:8443","10.101.229.1:8443"],"architectures":["x86_64","i686"],"certificate":"-----BEGIN CERTIFICATE-----\nMIIFWTCCA0GgAwIBAgIRAPchIDsBRbhRkmXzo12AHDswDQYJKoZIhvcNAQELBQAw\nPDEcMBoGA1UEChMTbGludXhjb250YWluZXJzLm9yZzEcMBoGA1UEAwwTcm9vdEBh\nc2FkLXVidW50dS0xNjAeFw0xNzAyMDEwMTI1MzFaFw0yNzAxMzAwMTI1MzFaMDwx\nHDAaBgNVBAoTE2xpbnV4Y29udGFpbmVycy5vcmcxHDAaBgNVBAMME3Jvb3RAYXNh\nZC11YnVudHUtMTYwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCix7my\nM4fGYRsoUiialbS5OsHICp4vtDIeJv4S/OA2FqUDhjH736sI4UR5FeC2UK8cQ73c\ns92TxUWMWrGCkQsq+k7yRYuvPyVXaSMqhmT62MlvQBFSMK4nxTEIN13raJsZ4dKj\n2PwR+VaRfJu9Z7H2uamNvcp6mSEH2yJ7Wc479c593fxv+gbq30Fpv3zO0yqyo1wc\nW0B0ML7vqCUr8IVkwO/1Bkn5vqAM9LOqWCu0i6kMnHXrBI3LvnNjVaUhyuRKHJKP\nZX97g1xU/npYd7ec0fHibaUb2fWvKoMIGvROKK3LzaCZj6yoVAhD48dg8lZFWXmv\ny8yg2KjHQM3ddfjuFafa5ea0YOUbXgsNMQjUp2pQu11ArPqyXwKglVwS10FgARd1\nI8eMtZZ5H/ZE8uwiO3s3x5/MUEKtEuXm5dUtE7L4ZyNW+7wX4fexJnxqa6flejfY\nwezAdBYZMY6QtvnkxjzcBZUjZ67cAnDNgbehxN12XlGcZUUhhW62TgfAIAvNNjIX\nuYSLUvvD4wnM3pgU8P5JzfXsgu9gqllL70x3HbC6WTjK9jj1bH8V5EpimBC2OtkV\nTEiPg1VOml8enq3pCan8gyQHufReJcq29jWnlRs1cWUCQp6sPWnke0CyMrr/bOeQ\nwD0kYqCxouU+m8g6tlsB9cgkMtJ++kMVoFfzOQIDAQABo1YwVDAOBgNVHQ8BAf8E\nBAMCBaAwEwYDVR0lBAwwCgYIKwYBBQUHAwEwDAYDVR0TAQH/BAIwADAfBgNVHREE\nGDAWgg5hc2FkLXVidW50dS0xNocErBCQYTANBgkqhkiG9w0BAQsFAAOCAgEAO2eF\nxwwayFySu87YjZDF8LN03H83/huN2IMCsIGjNGAocP8DGO71opQZLhTQU4IELxfc\nc8jmnbS96IqhL/8YilfKS+NiPBqdpPs1yAjPkiieoFN0SxG3vWM/oaKrDlGu0yhi\nfLg22b51UphhdWFYcwqB4MfzZyRIpgL/Ptgdf9hV0Pgm8gvgD3j5+1TYZ2CJX4WN\neNrhHU3DLqZtR2ffVpoTFM6rncp9GUKYlkAgVelbEoqmQqEFIYiuI+rCmRnlbJFB\naZ+yg3aMJ9Qu/IZVfuEE4mQX0NkxVB+Oo5a9RuBCppw5iO1fJQhKrFKq3wXea7BR\nfsT8kDleucS3VMDkZAPEB24HDxt4kDJ5imqVtaARkg3K+pDzj+M0Flw+7/YSP+Nb\n3Gj179NrT0kz5PZleWOZJQXVFzbIPzjushnFojXXue+QINhQzXAwY1yBtPDf0YrW\nPAD55HLmZYfzqUtGelfnDbUFD8U2VUp31ZsNsgZAgSWTiqH2hkGfc6ZI+Q8Wzzmr\n/xvikE1n9ZdR7lRPKo/RrDZt6vKermh1ELE3Ku9HsFzIj1E9VZVgB+vtqXF1qQwK\nv/bvKZf1u86w3P6BDHuFemfLLw9QoBMnNWHmXcwQgwhEAl5q18Y9advH3u2+1Woo\nmZqFaraseYVPsaG+AsLZ+DXrhmvywFFF9vVrLTI=\n-----END CERTIFICATE-----\n","driver":"lxc","driver_version":"2.0.6","kernel":"Linux","kernel_architecture":"x86_64","kernel_version":"4.4.0-31-generic","server":"lxd","server_pid":17652,"server_version":"2.0.8","storage":"zfs","storage_version":"5"},"public":false}}
2017-02-01 01:58:20 WARNING juju.cmd.jujud machine.go:808 determining kvm support: INFO: Your CPU does not support KVM extensions
KVM acceleration can NOT be used
: exit status 1
no kvm containers possible
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "tools-version-checker" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "mgo-txn-resumer" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "ssh-identity-writer" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "unit-agent-deployer" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "machiner" manifold worker started
2017-02-01 01:58:20 INFO juju.agent identity.go:22 writing system identity file
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "ssh-identity-writer" manifold worker stopped: <nil>
2017-02-01 01:58:20 INFO juju.worker.logforwarder logforwarder.go:103 config change - log forwarding not enabled
2017-02-01 01:58:20 DEBUG juju.utils.ssh authorisedkeys.go:92 reading authorised keys file /home/ubuntu/.ssh/authorized_keys
2017-02-01 01:58:20 DEBUG juju.utils.ssh authorisedkeys.go:92 reading authorised keys file /home/ubuntu/.ssh/authorized_keys
2017-02-01 01:58:20 DEBUG juju.utils.ssh authorisedkeys.go:129 writing authorised keys file /home/ubuntu/.ssh/authorized_keys
2017-02-01 01:58:20 INFO juju.cmd.jujud machine.go:972 update apiserver worker with new certificate
2017-02-01 01:58:20 INFO juju.worker.certupdater certupdater.go:174 controller cerificate addresses updated to ["10.101.229.96" "anything" "juju-apiserver" "juju-mongodb" "localhost"]
2017-02-01 01:58:20 INFO juju.apiserver apiserver.go:657 received API server certificate
2017-02-01 01:58:20 INFO juju.apiserver apiserver.go:684 new certificate addresses: 10.101.229.96
2017-02-01 01:58:20 DEBUG juju.tools.lxdclient client.go:199 connecting to LXD remote "remote": "10.101.229.1:8443"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":36,"type":"Agent","version":2,"request":"StateServingInfo","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":37,"type":"Provisioner","version":3,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":38,"type":"Deployer","version":1,"request":"WatchUnits","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":39,"type":"Machiner","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":40,"type":"KeyUpdater","version":1,"request":"WatchAuthorisedKeys","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":41,"type":"AgentTools","version":1,"request":"UpdateToolsAvailable","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":42,"type":"Resumer","version":2,"request":"ResumeTransactions","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.worker.certupdater certupdater.go:111 addresses haven't really changed since last updated cert
2017-02-01 01:58:20 DEBUG juju.apiserver admin.go:164 model login: machine-0 for 2c3090ee-3d0a-46df-8106-9d865f2b721b
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6]  66.308917ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:20 DEBUG juju.worker.apicaller connect.go:142 connected
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "api-caller" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "metric-worker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "state-cleaner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "environ-tracker" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "status-history-pruner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "machine-undertaker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "unit-assigner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "application-scaler" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "firewaller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.apiserver admin.go:164 model login: machine-0 for fb0f8b26-20d8-46ad-850f-0e846d8c5551
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":2,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":3,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":4,"type":"Singular","version":1,"request":"Claim","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5]  253.229993ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 13.602912ms {"request-id":3,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 21.343588ms {"request-id":2,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.worker.apicaller connect.go:142 connected
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "api-caller" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "not-dead-flag" manifold worker started
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "not-alive-flag" manifold worker started
2017-02-01 01:58:20 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":[{"name":"default","config":{},"description":"Default LXD profile","devices":{"eth0":{"name":"eth0","nictype":"bridged","parent":"lxdbr0","type":"nic"}}},{"name":"docker","config":{"linux.kernel_modules":"overlay, nf_nat","security.nesting":"true"},"description":"Profile supporting docker in containers","devices":{"aadisable":{"path":"/sys/module/apparmor/parameters/enabled","source":"/dev/null","type":"disk"}}},{"name":"juju-controller","config":{"boot.autostart":"true","security.nesting":"true"},"description":"","devices":{}},{"name":"juju-default","config":{"boot.autostart":"true","security.nesting":"true"},"description":"","devices":{}}]}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":5,"type":"LifeFlag","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":6,"type":"LifeFlag","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 5.674329ms {"request-id":5,"response":"'body redacted'"} LifeFlag[""].Watch
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:254 controller machines in state: []string{"0"}
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:272 found new machine "0"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 12.66139ms {"request-id":6,"response":"'body redacted'"} LifeFlag[""].Watch
2017-02-01 01:58:20 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":{"api_extensions":["id_map"],"api_status":"stable","api_version":"1.0","auth":"trusted","config":{"core.https_address":"[::]","storage.zfs_pool_name":"lxd"},"environment":{"addresses":["172.16.144.97:8443","10.101.229.1:8443"],"architectures":["x86_64","i686"],"certificate":"-----BEGIN CERTIFICATE-----\nMIIFWTCCA0GgAwIBAgIRAPchIDsBRbhRkmXzo12AHDswDQYJKoZIhvcNAQELBQAw\nPDEcMBoGA1UEChMTbGludXhjb250YWluZXJzLm9yZzEcMBoGA1UEAwwTcm9vdEBh\nc2FkLXVidW50dS0xNjAeFw0xNzAyMDEwMTI1MzFaFw0yNzAxMzAwMTI1MzFaMDwx\nHDAaBgNVBAoTE2xpbnV4Y29udGFpbmVycy5vcmcxHDAaBgNVBAMME3Jvb3RAYXNh\nZC11YnVudHUtMTYwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCix7my\nM4fGYRsoUiialbS5OsHICp4vtDIeJv4S/OA2FqUDhjH736sI4UR5FeC2UK8cQ73c\ns92TxUWMWrGCkQsq+k7yRYuvPyVXaSMqhmT62MlvQBFSMK4nxTEIN13raJsZ4dKj\n2PwR+VaRfJu9Z7H2uamNvcp6mSEH2yJ7Wc479c593fxv+gbq30Fpv3zO0yqyo1wc\nW0B0ML7vqCUr8IVkwO/1Bkn5vqAM9LOqWCu0i6kMnHXrBI3LvnNjVaUhyuRKHJKP\nZX97g1xU/npYd7ec0fHibaUb2fWvKoMIGvROKK3LzaCZj6yoVAhD48dg8lZFWXmv\ny8yg2KjHQM3ddfjuFafa5ea0YOUbXgsNMQjUp2pQu11ArPqyXwKglVwS10FgARd1\nI8eMtZZ5H/ZE8uwiO3s3x5/MUEKtEuXm5dUtE7L4ZyNW+7wX4fexJnxqa6flejfY\nwezAdBYZMY6QtvnkxjzcBZUjZ67cAnDNgbehxN12XlGcZUUhhW62TgfAIAvNNjIX\nuYSLUvvD4wnM3pgU8P5JzfXsgu9gqllL70x3HbC6WTjK9jj1bH8V5EpimBC2OtkV\nTEiPg1VOml8enq3pCan8gyQHufReJcq29jWnlRs1cWUCQp6sPWnke0CyMrr/bOeQ\nwD0kYqCxouU+m8g6tlsB9cgkMtJ++kMVoFfzOQIDAQABo1YwVDAOBgNVHQ8BAf8E\nBAMCBaAwEwYDVR0lBAwwCgYIKwYBBQUHAwEwDAYDVR0TAQH/BAIwADAfBgNVHREE\nGDAWgg5hc2FkLXVidW50dS0xNocErBCQYTANBgkqhkiG9w0BAQsFAAOCAgEAO2eF\nxwwayFySu87YjZDF8LN03H83/huN2IMCsIGjNGAocP8DGO71opQZLhTQU4IELxfc\nc8jmnbS96IqhL/8YilfKS+NiPBqdpPs1yAjPkiieoFN0SxG3vWM/oaKrDlGu0yhi\nfLg22b51UphhdWFYcwqB4MfzZyRIpgL/Ptgdf9hV0Pgm8gvgD3j5+1TYZ2CJX4WN\neNrhHU3DLqZtR2ffVpoTFM6rncp9GUKYlkAgVelbEoqmQqEFIYiuI+rCmRnlbJFB\naZ+yg3aMJ9Qu/IZVfuEE4mQX0NkxVB+Oo5a9RuBCppw5iO1fJQhKrFKq3wXea7BR\nfsT8kDleucS3VMDkZAPEB24HDxt4kDJ5imqVtaARkg3K+pDzj+M0Flw+7/YSP+Nb\n3Gj179NrT0kz5PZleWOZJQXVFzbIPzjushnFojXXue+QINhQzXAwY1yBtPDf0YrW\nPAD55HLmZYfzqUtGelfnDbUFD8U2VUp31ZsNsgZAgSWTiqH2hkGfc6ZI+Q8Wzzmr\n/xvikE1n9ZdR7lRPKo/RrDZt6vKermh1ELE3Ku9HsFzIj1E9VZVgB+vtqXF1qQwK\nv/bvKZf1u86w3P6BDHuFemfLLw9QoBMnNWHmXcwQgwhEAl5q18Y9advH3u2+1Woo\nmZqFaraseYVPsaG+AsLZ+DXrhmvywFFF9vVrLTI=\n-----END CERTIFICATE-----\n","driver":"lxc","driver_version":"2.0.6","kernel":"Linux","kernel_architecture":"x86_64","kernel_version":"4.4.0-31-generic","server":"lxd","server_pid":17652,"server_version":"2.0.8","storage":"zfs","storage_version":"5"},"public":false}}
2017-02-01 01:58:20 INFO juju.tools.lxdclient client.go:247 using LXD API version "1.0"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":7,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":8,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":9,"type":"NotifyWatcher","version":1,"id":"2","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":10,"type":"NotifyWatcher","version":1,"id":"1","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not set: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "metric-worker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "environ-tracker" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "firewaller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "application-scaler" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "status-history-pruner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "state-cleaner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "machine-undertaker" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "space-importer" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "compute-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "storage-provisioner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "instance-poller" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "not-dead-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "unit-assigner" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "charm-revision-updater" manifold worker stopped: "migration-inactive-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:303 machine "0" not ready: pending
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:254 controller machines in state: []string{"0"}
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:272 found new machine "0"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 9.595919ms {"request-id":8,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":2,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":3,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":4,"type":"Singular","version":1,"request":"Claim","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 10.113031ms {"request-id":7,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 5.591228ms {"request-id":2,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.apiserver admin.go:258 model login: user admin has "superuser" for controller; "admin" for model 2c3090ee-3d0a-46df-8106-9d865f2b721b
2017-02-01 01:58:20 DEBUG juju.worker.peergrouper worker.go:303 machine "0" not ready: pending
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "not-dead-flag" manifold worker started
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 7.203965ms {"request-id":3,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "not-alive-flag" manifold worker started
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":5,"type":"LifeFlag","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":6,"type":"LifeFlag","version":1,"request":"Watch","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [7]  304.606169ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 5.232919ms {"request-id":6,"response":"'body redacted'"} LifeFlag[""].Watch
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [7] user-admin {"request-id":2,"type":"Block","version":2,"request":"List","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 6.171141ms {"request-id":5,"response":"'body redacted'"} LifeFlag[""].Watch
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not set: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-inactive-flag" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-fortress" manifold worker stopped: "is-responsible-flag" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":7,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":8,"type":"LifeFlag","version":1,"request":"Life","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":9,"type":"NotifyWatcher","version":1,"id":"3","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [5] machine-0 {"request-id":10,"type":"NotifyWatcher","version":1,"id":"2","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 4.326499ms {"request-id":8,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [5] machine-0 4.695308ms {"request-id":7,"response":"'body redacted'"} LifeFlag[""].Life
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [7] user-admin 32.501644ms {"request-id":2,"response":"'body redacted'"} Block[""].List
2017-02-01 01:58:20 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":{"api_extensions":["id_map"],"api_status":"stable","api_version":"1.0","auth":"trusted","config":{"core.https_address":"[::]","storage.zfs_pool_name":"lxd"},"environment":{"addresses":["172.16.144.97:8443","10.101.229.1:8443"],"architectures":["x86_64","i686"],"certificate":"-----BEGIN CERTIFICATE-----\nMIIFWTCCA0GgAwIBAgIRAPchIDsBRbhRkmXzo12AHDswDQYJKoZIhvcNAQELBQAw\nPDEcMBoGA1UEChMTbGludXhjb250YWluZXJzLm9yZzEcMBoGA1UEAwwTcm9vdEBh\nc2FkLXVidW50dS0xNjAeFw0xNzAyMDEwMTI1MzFaFw0yNzAxMzAwMTI1MzFaMDwx\nHDAaBgNVBAoTE2xpbnV4Y29udGFpbmVycy5vcmcxHDAaBgNVBAMME3Jvb3RAYXNh\nZC11YnVudHUtMTYwggIiMA0GCSqGSIb3DQEBAQUAA4ICDwAwggIKAoICAQCix7my\nM4fGYRsoUiialbS5OsHICp4vtDIeJv4S/OA2FqUDhjH736sI4UR5FeC2UK8cQ73c\ns92TxUWMWrGCkQsq+k7yRYuvPyVXaSMqhmT62MlvQBFSMK4nxTEIN13raJsZ4dKj\n2PwR+VaRfJu9Z7H2uamNvcp6mSEH2yJ7Wc479c593fxv+gbq30Fpv3zO0yqyo1wc\nW0B0ML7vqCUr8IVkwO/1Bkn5vqAM9LOqWCu0i6kMnHXrBI3LvnNjVaUhyuRKHJKP\nZX97g1xU/npYd7ec0fHibaUb2fWvKoMIGvROKK3LzaCZj6yoVAhD48dg8lZFWXmv\ny8yg2KjHQM3ddfjuFafa5ea0YOUbXgsNMQjUp2pQu11ArPqyXwKglVwS10FgARd1\nI8eMtZZ5H/ZE8uwiO3s3x5/MUEKtEuXm5dUtE7L4ZyNW+7wX4fexJnxqa6flejfY\nwezAdBYZMY6QtvnkxjzcBZUjZ67cAnDNgbehxN12XlGcZUUhhW62TgfAIAvNNjIX\nuYSLUvvD4wnM3pgU8P5JzfXsgu9gqllL70x3HbC6WTjK9jj1bH8V5EpimBC2OtkV\nTEiPg1VOml8enq3pCan8gyQHufReJcq29jWnlRs1cWUCQp6sPWnke0CyMrr/bOeQ\nwD0kYqCxouU+m8g6tlsB9cgkMtJ++kMVoFfzOQIDAQABo1YwVDAOBgNVHQ8BAf8E\nBAMCBaAwEwYDVR0lBAwwCgYIKwYBBQUHAwEwDAYDVR0TAQH/BAIwADAfBgNVHREE\nGDAWgg5hc2FkLXVidW50dS0xNocErBCQYTANBgkqhkiG9w0BAQsFAAOCAgEAO2eF\nxwwayFySu87YjZDF8LN03H83/huN2IMCsIGjNGAocP8DGO71opQZLhTQU4IELxfc\nc8jmnbS96IqhL/8YilfKS+NiPBqdpPs1yAjPkiieoFN0SxG3vWM/oaKrDlGu0yhi\nfLg22b51UphhdWFYcwqB4MfzZyRIpgL/Ptgdf9hV0Pgm8gvgD3j5+1TYZ2CJX4WN\neNrhHU3DLqZtR2ffVpoTFM6rncp9GUKYlkAgVelbEoqmQqEFIYiuI+rCmRnlbJFB\naZ+yg3aMJ9Qu/IZVfuEE4mQX0NkxVB+Oo5a9RuBCppw5iO1fJQhKrFKq3wXea7BR\nfsT8kDleucS3VMDkZAPEB24HDxt4kDJ5imqVtaARkg3K+pDzj+M0Flw+7/YSP+Nb\n3Gj179NrT0kz5PZleWOZJQXVFzbIPzjushnFojXXue+QINhQzXAwY1yBtPDf0YrW\nPAD55HLmZYfzqUtGelfnDbUFD8U2VUp31ZsNsgZAgSWTiqH2hkGfc6ZI+Q8Wzzmr\n/xvikE1n9ZdR7lRPKo/RrDZt6vKermh1ELE3Ku9HsFzIj1E9VZVgB+vtqXF1qQwK\nv/bvKZf1u86w3P6BDHuFemfLLw9QoBMnNWHmXcwQgwhEAl5q18Y9advH3u2+1Woo\nmZqFaraseYVPsaG+AsLZ+DXrhmvywFFF9vVrLTI=\n-----END CERTIFICATE-----\n","driver":"lxc","driver_version":"2.0.6","kernel":"Linux","kernel_architecture":"x86_64","kernel_version":"4.4.0-31-generic","server":"lxd","server_pid":17652,"server_version":"2.0.8","storage":"zfs","storage_version":"5"},"public":false}}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:80 [7] user-admin API connection terminated after 442.185016ms
2017-02-01 01:58:20 DEBUG juju.worker.lease manager.go:217 waking to check leases at 2017-02-01 01:59:20.449101433 +0000 UTC
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [6] machine-0 234.895274ms {"request-id":4,"response":"'body redacted'"} Singular[""].Claim
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "is-responsible-flag" manifold worker started
2017-02-01 01:58:20 DEBUG lxd client.go:68 Raw response: {"type":"sync","status":"Success","status_code":200,"metadata":[{"name":"default","config":{},"description":"Default LXD profile","devices":{"eth0":{"name":"eth0","nictype":"bridged","parent":"lxdbr0","type":"nic"}}},{"name":"docker","config":{"linux.kernel_modules":"overlay, nf_nat","security.nesting":"true"},"description":"Profile supporting docker in containers","devices":{"aadisable":{"path":"/sys/module/apparmor/parameters/enabled","source":"/dev/null","type":"disk"}}},{"name":"juju-controller","config":{"boot.autostart":"true","security.nesting":"true"},"description":"","devices":{}},{"name":"juju-default","config":{"boot.autostart":"true","security.nesting":"true"},"description":"","devices":{}}]}
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 631.085537ms {"request-id":32,"response":"'body redacted'"} Reboot[""].WatchForRebootEvent
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 635.405336ms {"request-id":33,"response":"'body redacted'"} Machiner[""].WatchAPIHostPorts
2017-02-01 01:58:20 DEBUG juju.mongo open.go:142 dialled mongodb server at "127.0.0.1:37017"
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 373.146837ms {"request-id":39,"response":"'body redacted'"} Machiner[""].Life
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "undertaker" manifold worker stopped: "not-alive-flag" not set: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "migration-master" manifold worker stopped: "migration-fortress" not running: dependency not available
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:478 "migration-fortress" manifold worker started
2017-02-01 01:58:20 DEBUG juju.network network.go:402 no lxc bridge addresses to filter for machine
2017-02-01 01:58:20 WARNING juju.network network.go:447 cannot get "lxdbr0" addresses: route ip+net: no such network interface (ignoring)
2017-02-01 01:58:20 INFO juju.worker.machiner machiner.go:142 setting addresses for machine-0 to ["local-machine:127.0.0.1" "local-cloud:10.101.229.96" "local-machine:::1"]
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 393.469601ms {"request-id":40,"response":"'body redacted'"} KeyUpdater[""].WatchAuthorisedKeys
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 654.38737ms {"request-id":30,"response":"'body redacted'"} MachineActions[""].RunningActions
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 654.973284ms {"request-id":31,"response":"'body redacted'"} ProxyUpdater[""].ProxyConfig
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 382.423648ms {"request-id":36,"response":"'body redacted'"} Agent[""].StateServingInfo
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 654.100464ms {"request-id":29,"response":"'body redacted'"} StorageProvisioner[""].WatchBlockDevices
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:140 -> [4] machine-0 653.704155ms {"request-id":34,"response":"'body redacted'"} Logger[""].LoggingConfig
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":43,"type":"Reboot","version":2,"request":"GetRebootAction","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":44,"type":"Machiner","version":1,"request":"APIHostPorts","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":45,"type":"NotifyWatcher","version":1,"id":"6","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":46,"type":"NotifyWatcher","version":1,"id":"7","request":"Next","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [4] machine-0 {"request-id":47,"type":"Machiner","version":1,"request":"SetMachineAddresses","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":11,"type":"MigrationFlag","version":1,"request":"Phase","params":"'params redacted'"}
2017-02-01 01:58:20 DEBUG juju.apiserver request_notifier.go:115 <- [6] machine-0 {"request-id":12,"type":"Agent","version":2,"request":"ModelConfig","params":"'params redacted'"}
2017-02-01 01:58:20 INFO juju.worker.authenticationworker worker.go:102 "machine-0" key updater worker started
2017-02-01 01:58:20 DEBUG juju.worker.proxyupdater proxyupdater.go:151 new proxy settings proxy.Settings{Http:"", Https:"", Ftp:"", NoProxy:"10.101.229.96"}
2017-02-01 01:58:20 DEBUG juju.worker.logger logger.go:50 reconfiguring logging from "<root>=DEBUG" to "<root>=WARNING;unit=DEBUG"
2017-02-01 01:58:20 DEBUG juju.worker.dependency engine.go:492 "serving-info-setter" manifold worker stopped: resource permanently unavailable
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.worker.dependency engine.go:539 "unit-agent-deployer" manifold worker returned unexpected error: connection is shut down
2017-02-01 01:58:21 ERROR juju.worker.dependency engine.go:539 "tools-version-checker" manifold worker returned unexpected error: cannot update tools information: connection is shut down
2017-02-01 01:58:21 ERROR juju.worker.dependency engine.go:539 "machiner" manifold worker returned unexpected error: setting machine addresses: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.worker.logger logger.go:47 connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.api.watcher watcher.go:86 error trying to stop watcher: connection is shut down
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 WARNING juju.apiserver.metricsmanager metricsmanager.go:169 failed to send metrics for model-fb0f8b26-20d8-46ad-850f-0e846d8c5551: metrics manager not found
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:21 WARNING juju.worker.metricworker.sender sender.go:25 failed to send metrics failed to send metrics for model-fb0f8b26-20d8-46ad-850f-0e846d8c5551: metrics manager not found - will retry later
2017-02-01 01:58:21 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:22 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:22 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe
2017-02-01 01:58:24 WARNING juju.cmd.jujud machine.go:808 determining kvm support: INFO: Your CPU does not support KVM extensions
KVM acceleration can NOT be used
: exit status 1
no kvm containers possible
2017-02-01 01:58:27 ERROR juju.rpc server.go:510 error writing response: write tcp 127.0.0.1:17070->127.0.0.1:37364: write: broken pipe

Revision history for this message

Asad Saeeduddin (masaeedu) wrote on 2017-02-01:

machine-0.log Edit (94.0 KiB, text/plain)

Sorry, not sure if previous comment was snipped. Here it is again as an attachment.

Revision history for this message

Anastasia (anastasia-macmood) wrote on 2017-02-01:

Thank you for attaching the log \o/

Could you please describe what you are bootstrapping - command arguments, configuration options?
Could you please provide log with TRACE enabled? It might shed more light on what has happened.

Also it is not clear from given information where the environment recovered or whether it is now unusable for you.

Revision history for this message

Asad Saeeduddin (masaeedu) wrote on 2017-02-01:

I am following the instructions here: https://insights.ubuntu.com/2016/06/08/lxd-2-0-lxd-and-juju/ to get juju up and running with a local LXD cloud in a Ubuntu 16.04 desktop Hyper-V VM.

Here are the steps I followed on first boot:

- Install lxd, zfs utils
- Run `sudo lxd init` and accept defaults for all options **except** IPv6
- Reboot to avoid any issues with groups
- Run `sudo apt-get install juju -y`
- Run `juju bootstrap`, accept the default "localhost" as the cloud and the default "localhost-localhost" as the name of the controller
- As soon as container provisioning completes and the `juju bootstrap` command exits, run `juju switch controller`, followed by `juju debug-log --replay`

This produces log output containing the errors I posted.

I arrived at this issue while trying to diagnose why invoking `juju deploy <application>` results in containers stuck in pending state, and no output in the "default" model's `juju debug-log`. I spotted the port 17070 errors in the "controller" model's log and tried setting up from scratch, and found that I get errors in the log before ever trying to deploy any applications. I am not sure if this is the root cause of the issue I'm seeing, but it can't hurt to try and settle this bug one way or another.

I am not at my computer at the moment but will provide the extra information you requested some time tomorrow.

Revision history for this message

Asad Saeeduddin (masaeedu) wrote on 2017-02-02:

@anastasia I've been looking at the docs, but can't figure out how I would enable TRACE. Could you point me in the right direction?

Revision history for this message

Anastasia (anastasia-macmood) wrote on 2017-02-03:

Something along the lines of
`JUJU_LOGGING_CONFIG="<root>=TRACE" juju bootstrap ...`