May 21 21:46:10 ubuntu-xenial sshd[1133]: Server listening on :: port 22. May 21 21:46:10 ubuntu-xenial sshd[1133]: Server listening on 0.0.0.0 port 22. May 21 21:46:10 ubuntu-xenial systemd[1]: Reloaded OpenBSD Secure Shell server. May 21 21:46:10 ubuntu-xenial sshd[1133]: Received SIGHUP; restarting. May 21 21:46:10 ubuntu-xenial systemd[1]: Reloading OpenBSD Secure Shell server. May 21 21:46:10 ubuntu-xenial systemd[1]: Started Initial cloud-init job (metadata service crawler). May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | 1 | 10.0.2.0 | 0.0.0.0 | 255.255.255.0 | enp0s3 | U | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | 0 | 0.0.0.0 | 10.0.2.2 | 0.0.0.0 | enp0s3 | UG | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | enp0s3 | True | fe80::40:5ff:fec5:1735/64 | . | link | 02:40:05:c5:17:35 | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | enp0s3 | True | 10.0.2.15 | 255.255.255.0 | . | 02:40:05:c5:17:35 | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | lo | True | ::1/128 | . | host | . | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | . | . | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | enp0s8 | False | . | . | . | 08:00:27:83:40:69 | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: ci-info: +++++++++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++++++++ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: Cloud-init v. 0.7.7 running 'init' at Sat, 21 May 2016 21:46:10 +0000. Up 301.73 seconds. May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.152 seconds (0.15) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 15 bytes from /proc/uptime May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cloud-init[DEBUG]: Ran 14 modules with 0 failures May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module ssh () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module users-groups () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-rsyslog already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module rsyslog () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-ca-certs already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module ca-certs () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_etc_hosts using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module update_etc_hosts () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Attempting to update hostname to ubuntu-xenial in 0 files May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 14 bytes from /etc/hostname May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/hostname (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 14 bytes from /var/lib/cloud/data/previous-hostname May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_update_hostname.py[DEBUG]: Updating hostname to ubuntu-xenial.localdomain (ubuntu-xenial) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_hostname using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module update_hostname () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module set_hostname () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Resizing took 0.003 seconds May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ('resize2fs', '/dev/sda1') with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/sda1 May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 895 bytes from /proc/self/status May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/self/status (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 152 bytes from /proc/1/environ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_resizefs.py[DEBUG]: resize_info: dev=/dev/sda1 mnt_point=/ path=/ May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 4282 bytes from /proc/1627/mountinfo May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1627/mountinfo (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-resizefs using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module resizefs () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/sda, 1) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: resize_devices took 0.026 seconds May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 4 bytes from /sys/devices/pci0000:00/0000:00:14.0/host2/target2:0:0/2:0:0:0/block/sda/dev May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:14.0/host2/target2:0:0/2:0:0:0/block/sda/dev (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 4282 bytes from /proc/1627/mountinfo May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1627/mountinfo (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'ignore_growroot_disabled': False, 'mode': 'auto', 'devices': ['/']} May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-growpart using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module growpart () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-write-files already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module write-files () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-bootcmd using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module bootcmd () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-seed_random already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module seed_random () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ubuntu-init-switch: SUCCESS: config-ubuntu-init-switch previously ran May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: config-ubuntu-init-switch already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ubuntu-init-switch: running config-ubuntu-init-switch with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module ubuntu-init-switch () with frequency once-per-instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: Running config-migrator using lock () May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Running module migrator () with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: no vendordata from datasource May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/cloud-config.txt - wb: [384] 130 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 94 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: {'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config'} May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] helpers.py[DEBUG]: consume_data already ran (freq=once-per-instance) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/vendor-data.txt.i - wb: [384] 308 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/vendor-data.txt - wb: [384] 0 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/user-data.txt.i - wb: [384] 399 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 94 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/user-data.txt - wb: [384] 94 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 5281 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cloud-init[DEBUG]: init will now be targeting instance id: iid-9d97397c0beec404 May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 21 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 21 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 21 bytes from /var/lib/cloud/data/instance-id May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 71 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/datasource - wb: [420] 71 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 71 bytes from /var/lib/cloud/instances/iid-9d97397c0beec404/datasource May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instances/iid-9d97397c0beec404/datasource (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/iid-9d97397c0beec404' May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache: DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] stages.py[DEBUG]: restored from cache: DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 5191 bytes from /var/lib/cloud/instance/obj.pkl May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cloud-init[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Read 5191 bytes from /var/lib/cloud/instance/obj.pkl May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/no-net (quiet=False) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] cloud-init[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True) May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes May 21 21:46:10 ubuntu-xenial cloud-init[1627]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'init' at Sat, 21 May 2016 21:46:10 +0000. Up 301.73 seconds. May 21 21:46:09 ubuntu-xenial systemd-logind[827]: Removed session 1. May 21 21:46:09 ubuntu-xenial sshd[1224]: pam_unix(sshd:session): session closed for user ubuntu May 21 21:46:09 ubuntu-xenial sshd[1285]: Disconnected from 10.0.2.2 port 60624 May 21 21:46:09 ubuntu-xenial sshd[1285]: Received disconnect from 10.0.2.2 port 60624:11: disconnected by user May 21 21:46:09 ubuntu-xenial systemd[1]: Starting Initial cloud-init job (metadata service crawler)... May 21 21:46:09 ubuntu-xenial sudo[1585]: pam_unix(sudo:session): session closed for user root May 21 21:46:09 ubuntu-xenial sudo[1286]: pam_unix(sudo:session): session closed for user root May 21 21:46:09 ubuntu-xenial systemd[1]: Started Raise network interfaces. May 21 21:46:09 ubuntu-xenial systemd[1]: Starting Raise network interfaces... May 21 21:46:09 ubuntu-xenial systemd[1]: Stopped Raise network interfaces. May 21 21:46:09 ubuntu-xenial sudo[1585]: pam_unix(sudo:session): session opened for user root by ubuntu(uid=0) May 21 21:46:09 ubuntu-xenial sudo[1585]: ubuntu : TTY=pts/0 ; PWD=/home/ubuntu ; USER=root ; COMMAND=/bin/systemctl restart networking May 21 21:46:09 ubuntu-xenial sudo[1585]: ubuntu : unable to resolve host ubuntu-xenial May 21 21:42:52 ubuntu-xenial sudo[1577]: pam_unix(sudo:session): session closed for user root May 21 21:42:16 ubuntu-xenial sudo[1577]: pam_unix(sudo:session): session opened for user root by ubuntu(uid=0) May 21 21:42:16 ubuntu-xenial sudo[1577]: ubuntu : TTY=pts/0 ; PWD=/home/ubuntu ; USER=root ; COMMAND=/bin/systemctl status networking May 21 21:42:16 ubuntu-xenial sudo[1577]: ubuntu : unable to resolve host ubuntu-xenial May 21 21:42:05 ubuntu-xenial systemd[1]: Started Session 2 of user ubuntu. May 21 21:42:05 ubuntu-xenial systemd-logind[827]: New session 2 of user ubuntu. May 21 21:42:05 ubuntu-xenial sshd[1528]: pam_unix(sshd:session): session opened for user ubuntu by (uid=0) May 21 21:42:05 ubuntu-xenial sshd[1528]: Accepted publickey for ubuntu from 10.0.2.2 port 60626 ssh2: RSA SHA256:gshchQWRYtt+sA/gcTVKrroxq+PUDHMQRhSYxlday44 May 21 21:41:58 ubuntu-xenial ifup[1383]: bound to 10.0.2.15 -- renewal in 40673 seconds. May 21 21:41:58 ubuntu-xenial dhclient[1447]: bound to 10.0.2.15 -- renewal in 40673 seconds. May 21 21:41:58 ubuntu-xenial dhclient[1447]: DHCPACK of 10.0.2.15 from 10.0.2.2 May 21 21:41:58 ubuntu-xenial dhclient[1447]: DHCPOFFER of 10.0.2.15 from 10.0.2.2 May 21 21:41:58 ubuntu-xenial dhclient[1447]: DHCPREQUEST of 10.0.2.15 on enp0s3 to 255.255.255.255 port 67 (xid=0xb92c5c0) May 21 21:41:58 ubuntu-xenial dhclient[1447]: DHCPDISCOVER on enp0s3 to 255.255.255.255 port 67 interval 3 (xid=0xc0c5920b) May 21 21:41:58 ubuntu-xenial dhclient[1447]: Sending on Socket/fallback May 21 21:41:58 ubuntu-xenial ifup[1383]: DHCPACK of 10.0.2.15 from 10.0.2.2 May 21 21:41:58 ubuntu-xenial ifup[1383]: DHCPOFFER of 10.0.2.15 from 10.0.2.2 May 21 21:41:58 ubuntu-xenial ifup[1383]: DHCPREQUEST of 10.0.2.15 on enp0s3 to 255.255.255.255 port 67 (xid=0xb92c5c0) May 21 21:41:58 ubuntu-xenial dhclient[1447]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:58 ubuntu-xenial ifup[1383]: DHCPDISCOVER on enp0s3 to 255.255.255.255 port 67 interval 3 (xid=0xc0c5920b) May 21 21:41:58 ubuntu-xenial ifup[1383]: Sending on Socket/fallback May 21 21:41:58 ubuntu-xenial ifup[1383]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:58 ubuntu-xenial ifup[1383]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:58 ubuntu-xenial dhclient[1447]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:58 ubuntu-xenial kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp0s3: link becomes ready May 21 21:41:58 ubuntu-xenial kernel: e1000: enp0s3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX May 21 21:41:58 ubuntu-xenial kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s3: link is not ready May 21 21:41:58 ubuntu-xenial dhclient[1447]: May 21 21:41:58 ubuntu-xenial ifup[1383]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:58 ubuntu-xenial dhclient[1447]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:58 ubuntu-xenial ifup[1383]: All rights reserved. May 21 21:41:58 ubuntu-xenial dhclient[1447]: All rights reserved. May 21 21:41:58 ubuntu-xenial ifup[1383]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:58 ubuntu-xenial dhclient[1447]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:58 ubuntu-xenial ifup[1383]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:58 ubuntu-xenial dhclient[1447]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:58 ubuntu-xenial systemd[1]: Starting Raise network interfaces... May 21 21:41:58 ubuntu-xenial systemd[1]: Stopped Raise network interfaces. May 21 21:41:57 ubuntu-xenial dhclient[1341]: DHCPRELEASE on enp0s3 to 10.0.2.2 port 67 (xid=0x1fdc5ce9) May 21 21:41:57 ubuntu-xenial dhclient[1341]: Sending on Socket/fallback May 21 21:41:57 ubuntu-xenial ifdown[1288]: DHCPRELEASE on enp0s3 to 10.0.2.2 port 67 (xid=0x1fdc5ce9) May 21 21:41:57 ubuntu-xenial dhclient[1341]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:57 ubuntu-xenial ifdown[1288]: Sending on Socket/fallback May 21 21:41:57 ubuntu-xenial ifdown[1288]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:57 ubuntu-xenial ifdown[1288]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:57 ubuntu-xenial dhclient[1341]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:57 ubuntu-xenial dhclient[1341]: May 21 21:41:57 ubuntu-xenial dhclient[1341]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:57 ubuntu-xenial dhclient[1341]: All rights reserved. May 21 21:41:57 ubuntu-xenial dhclient[1341]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:57 ubuntu-xenial ifdown[1288]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:57 ubuntu-xenial ifdown[1288]: All rights reserved. May 21 21:41:57 ubuntu-xenial ifdown[1288]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:57 ubuntu-xenial ifdown[1288]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:57 ubuntu-xenial dhclient[1341]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:56 ubuntu-xenial ifdown[1288]: Killed old client process May 21 21:41:56 ubuntu-xenial dhclient[1341]: Killed old client process May 21 21:41:56 ubuntu-xenial systemd[1]: Stopping Raise network interfaces... May 21 21:41:56 ubuntu-xenial systemd[1]: Stopping Initial cloud-init job (metadata service crawler)... May 21 21:41:56 ubuntu-xenial systemd[1]: Stopped Initial cloud-init job (metadata service crawler). May 21 21:41:56 ubuntu-xenial sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 21:41:56 ubuntu-xenial sudo[1286]: ubuntu : TTY=unknown ; PWD=/home/ubuntu ; USER=root ; COMMAND=/bin/systemctl restart networking May 21 21:41:56 ubuntu-xenial sudo[1286]: ubuntu : unable to resolve host ubuntu-xenial May 21 21:41:56 ubuntu-xenial systemd[1]: Started User Manager for UID 1000. May 21 21:41:56 ubuntu-xenial systemd[1226]: Startup finished in 11ms. May 21 21:41:56 ubuntu-xenial systemd[1226]: Reached target Default. May 21 21:41:56 ubuntu-xenial systemd[1226]: Reached target Basic System. May 21 21:41:56 ubuntu-xenial systemd[1226]: Reached target Timers. May 21 21:41:56 ubuntu-xenial systemd[1226]: Reached target Sockets. May 21 21:41:56 ubuntu-xenial systemd[1226]: Reached target Paths. May 21 21:41:56 ubuntu-xenial systemd[1]: Started Session 1 of user ubuntu. May 21 21:41:56 ubuntu-xenial systemd[1226]: pam_unix(systemd-user:session): session opened for user ubuntu by (uid=0) May 21 21:41:56 ubuntu-xenial systemd-logind[827]: New session 1 of user ubuntu. May 21 21:41:56 ubuntu-xenial systemd[1]: Starting User Manager for UID 1000... May 21 21:41:56 ubuntu-xenial systemd[1]: Created slice User Slice of ubuntu. May 21 21:41:56 ubuntu-xenial sshd[1224]: pam_unix(sshd:session): session opened for user ubuntu by (uid=0) May 21 21:41:56 ubuntu-xenial sshd[1224]: Accepted publickey for ubuntu from 10.0.2.2 port 60624 ssh2: RSA SHA256:gshchQWRYtt+sA/gcTVKrroxq+PUDHMQRhSYxlday44 May 21 21:41:55 ubuntu-xenial systemd[1]: apt-daily.timer: Adding 11h 40min 8.493682s random time. May 21 21:41:55 ubuntu-xenial systemd-timesyncd[700]: Synchronized to time server 91.189.94.4:123 (ntp.ubuntu.com). May 21 21:41:55 ubuntu-xenial systemd[1]: Time has been changed May 21 21:41:26 ubuntu-xenial systemd[1]: Startup finished in 11.602s (kernel) + 9.116s (userspace) = 20.718s. May 21 21:41:26 ubuntu-xenial systemd[1]: Started Update UTMP about System Runlevel Changes. May 21 21:41:26 ubuntu-xenial systemd[1]: Starting Update UTMP about System Runlevel Changes... May 21 21:41:26 ubuntu-xenial iscsid[1138]: iSCSI daemon with pid=1139 started! May 21 21:41:26 ubuntu-xenial systemd[1]: Reached target Graphical Interface. May 21 21:41:26 ubuntu-xenial systemd[1]: Reached target Multi-User System. May 21 21:41:26 ubuntu-xenial systemd[1]: Reached target Cloud-init target. May 21 21:41:26 ubuntu-xenial systemd[1]: Started Execute cloud user/final scripts. May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final May 21 21:41:26 ubuntu-xenial cloud-init[1207]: Cloud-init v. 0.7.7 finished at Sat, 21 May 2016 21:41:26 +0000. Datasource DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net]. Up 20.52 seconds May 21 21:41:26 ubuntu-xenial cloud-init[1207]: Cloud-init v. 0.7.7 running 'modules:final' at Sat, 21 May 2016 21:41:26 +0000. Up 20.46 seconds. May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'modules' took 0.068 seconds (0.07) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] cloud-init[DEBUG]: Ran 11 modules with 0 failures May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-power-state-change already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module power-state-change () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [420] 51 bytes May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 finished at Sat, 21 May 2016 21:41:26 +0000. Datasource DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net]. Up 20.52 seconds May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: Running config-final-message using lock () May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module final-message () with frequency always May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-phone-home already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module phone-home () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-keys-to-console already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module keys-to-console () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-ssh-authkey-fingerprints already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module ssh-authkey-fingerprints () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-scripts-user already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module scripts-user () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-scripts-per-instance already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module scripts-per-instance () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: Running config-scripts-per-boot using lock () May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module scripts-per-boot () with frequency always May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-scripts-per-once already ran (freq=once) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module scripts-per-once () with frequency once May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-scripts-vendor already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module scripts-vendor () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata previously ran May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] helpers.py[DEBUG]: config-rightscale_userdata already ran (freq=once-per-instance) May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Running module rightscale_userdata () with frequency once-per-instance May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:26 ubuntu-xenial cloud-init[1207]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'modules:final' at Sat, 21 May 2016 21:41:26 +0000. Up 20.46 seconds. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Execute cloud user/final scripts... May 21 21:41:25 ubuntu-xenial systemd[1]: Started Apply the settings specified in cloud-config. May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config May 21 21:41:25 ubuntu-xenial systemd[1]: Reached target Login Prompts. May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'modules' took 0.094 seconds (0.09) May 21 21:41:25 ubuntu-xenial systemd[1]: Started Getty on tty1. May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime May 21 21:41:25 ubuntu-xenial cloud-init[1129]: Cloud-init v. 0.7.7 running 'modules:config' at Sat, 21 May 2016 21:41:25 +0000. Up 19.87 seconds. May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] cloud-init[DEBUG]: Ran 22 modules with 0 failures May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-byobu already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module byobu () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-runcmd already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module runcmd () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module disable-ec2-metadata () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-mcollective: SUCCESS: config-mcollective previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-mcollective already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-mcollective: running config-mcollective with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module mcollective () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-salt-minion: SUCCESS: config-salt-minion previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-salt-minion already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-salt-minion: running config-salt-minion with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module salt-minion () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-chef: SUCCESS: config-chef previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-chef already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-chef: running config-chef with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module chef () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-puppet: SUCCESS: config-puppet previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-puppet already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-puppet: running config-puppet with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module puppet () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-lxd: SUCCESS: config-lxd previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-lxd already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-lxd: running config-lxd with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module lxd () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-timezone already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module timezone () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-landscape: SUCCESS: config-landscape previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-landscape already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-landscape: running config-landscape with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module landscape () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-fan: SUCCESS: config-fan previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-fan already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-fan: running config-fan with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module fan () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-package-update-upgrade-install already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module package-update-upgrade-install () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-apt-configure already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module apt-configure () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-apt-pipelining: SUCCESS: config-apt-pipelining previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-apt-pipelining already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-apt-pipelining: running config-apt-pipelining with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module apt-pipelining () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-grub-dpkg: SUCCESS: config-grub-dpkg previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-grub-dpkg already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-grub-dpkg: running config-grub-dpkg with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module grub-dpkg () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-snappy: SUCCESS: config-snappy previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-snappy already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-snappy: running config-snappy with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module snappy () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-set-passwords already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module set-passwords () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-locale already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module locale () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-ssh-import-id already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module ssh-import-id () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-mounts: SUCCESS: config-mounts previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-mounts already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-mounts: running config-mounts with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module mounts () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-disk_setup: SUCCESS: config-disk_setup previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: config-disk_setup already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-disk_setup: running config-disk_setup with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module disk_setup () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: finish: modules-config/config-emit_upstart: SUCCESS: config-emit_upstart ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] cc_emit_upstart.py[DEBUG]: not upstart system, '%s' disabled May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] cc_emit_upstart.py[DEBUG]: no /sbin/initctl located May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] helpers.py[DEBUG]: Running config-emit_upstart using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] handlers.py[DEBUG]: start: modules-config/config-emit_upstart: running config-emit_upstart with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Running module emit_upstart () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:25 ubuntu-xenial systemd[1]: Started Serial Getty on ttyS0. May 21 21:41:25 ubuntu-xenial cloud-init[1129]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'modules:config' at Sat, 21 May 2016 21:41:25 +0000. Up 19.87 seconds. May 21 21:41:25 ubuntu-xenial systemd[1]: Started Terminate Plymouth Boot Screen. May 21 21:41:25 ubuntu-xenial systemd[1]: Started Hold until boot process finishes up. May 21 21:41:25 ubuntu-xenial systemd[1]: Started LSB: daemon to balance interrupts for SMP systems. May 21 21:41:25 ubuntu-xenial irqbalance[1154]: ...done. May 21 21:41:25 ubuntu-xenial irqbalance[1154]: * Starting SMP IRQ Balancer: irqbalance May 21 21:41:25 ubuntu-xenial systemd[1]: Started LSB: Set the CPU Frequency Scaling governor to "ondemand". May 21 21:41:25 ubuntu-xenial systemd[1]: Started LSB: automatic crash report generation. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Terminate Plymouth Boot Screen... May 21 21:41:25 ubuntu-xenial apport[1152]: ...done. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Hold until boot process finishes up... May 21 21:41:25 ubuntu-xenial apport[1152]: * Starting automatic crash report generation: apport May 21 21:41:25 ubuntu-xenial systemd[1]: Started Permit User Sessions. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting LSB: Set the CPU Frequency Scaling governor to "ondemand"... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Permit User Sessions... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting LSB: daemon to balance interrupts for SMP systems... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting LSB: automatic crash report generation... May 21 21:41:25 ubuntu-xenial systemd[1]: Reached target Remote File Systems. May 21 21:41:25 ubuntu-xenial systemd[1]: Reached target Remote File Systems (Pre). May 21 21:41:25 ubuntu-xenial systemd[1]: Started Login to default iSCSI targets. May 21 21:41:25 ubuntu-xenial iscsiadm[1145]: iscsiadm: No records found May 21 21:41:25 ubuntu-xenial systemd[1]: Started OpenBSD Secure Shell server. May 21 21:41:25 ubuntu-xenial sshd[1133]: Server listening on :: port 22. May 21 21:41:25 ubuntu-xenial sshd[1133]: Server listening on 0.0.0.0 port 22. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Login to default iSCSI targets... May 21 21:41:25 ubuntu-xenial systemd[1]: Started iSCSI initiator daemon (iscsid). May 21 21:41:25 ubuntu-xenial iscsid[1136]: iSCSI logger with pid=1138 started! May 21 21:41:25 ubuntu-xenial systemd[1]: Started /etc/rc.local Compatibility. May 21 21:41:25 ubuntu-xenial systemd[1]: Starting OpenBSD Secure Shell server... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting /etc/rc.local Compatibility... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting Apply the settings specified in cloud-config... May 21 21:41:25 ubuntu-xenial systemd[1]: Starting iSCSI initiator daemon (iscsid)... May 21 21:41:25 ubuntu-xenial systemd[1]: Reached target Network is Online. May 21 21:41:25 ubuntu-xenial systemd[1]: Reached target Cloud-config availability. May 21 21:41:25 ubuntu-xenial systemd[1]: Started Initial cloud-init job (metadata service crawler). May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | 1 | 10.0.2.0 | 0.0.0.0 | 255.255.255.0 | enp0s3 | U | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | 0 | 0.0.0.0 | 10.0.2.2 | 0.0.0.0 | enp0s3 | UG | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +-------+-------------+----------+---------------+-----------+-------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | enp0s8 | False | . | . | . | 08:00:27:83:40:69 | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | enp0s3 | True | fe80::40:5ff:fec5:1735/64 | . | link | 02:40:05:c5:17:35 | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | enp0s3 | True | 10.0.2.15 | 255.255.255.0 | . | 02:40:05:c5:17:35 | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | lo | True | ::1/128 | . | host | . | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | . | . | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +--------+-------+---------------------------+---------------+-------+-------------------+ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: ci-info: +++++++++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++++++++ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: Cloud-init v. 0.7.7 running 'init' at Sat, 21 May 2016 21:41:24 +0000. Up 19.20 seconds. May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.322 seconds (0.33) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cloud-init[DEBUG]: Ran 14 modules with 0 failures May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module ssh () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module users-groups () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-rsyslog already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module rsyslog () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-ca-certs already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module ca-certs () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_etc_hosts using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module update_etc_hosts () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Attempting to update hostname to ubuntu-xenial in 0 files May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 14 bytes from /etc/hostname May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/hostname (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 14 bytes from /var/lib/cloud/data/previous-hostname May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_update_hostname.py[DEBUG]: Updating hostname to ubuntu-xenial.localdomain (ubuntu-xenial) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_hostname using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module update_hostname () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module set_hostname () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Resizing took 0.007 seconds May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ('resize2fs', '/dev/sda1') with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/sda1 May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 896 bytes from /proc/self/status May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/self/status (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 152 bytes from /proc/1/environ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_resizefs.py[DEBUG]: resize_info: dev=/dev/sda1 mnt_point=/ path=/ May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 4161 bytes from /proc/1077/mountinfo May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1077/mountinfo (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-resizefs using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module resizefs () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/sda, 1) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: resize_devices took 0.032 seconds May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 4 bytes from /sys/devices/pci0000:00/0000:00:14.0/host2/target2:0:0/2:0:0:0/block/sda/dev May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:14.0/host2/target2:0:0/2:0:0:0/block/sda/dev (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 4161 bytes from /proc/1077/mountinfo May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1077/mountinfo (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'devices': ['/'], 'ignore_growroot_disabled': False, 'mode': 'auto'} May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-growpart using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module growpart () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-write-files already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module write-files () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-bootcmd using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module bootcmd () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-seed_random already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module seed_random () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ubuntu-init-switch: SUCCESS: config-ubuntu-init-switch previously ran May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: config-ubuntu-init-switch already ran (freq=once-per-instance) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ubuntu-init-switch: running config-ubuntu-init-switch with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module ubuntu-init-switch () with frequency once-per-instance May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: Running config-migrator using lock () May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Running module migrator () with frequency always May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:41:25 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: no vendordata from datasource May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/cloud-config.txt - wb: [384] 130 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 94 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: {'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config'} May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] helpers.py[DEBUG]: consume_data already ran (freq=once-per-instance) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/vendor-data.txt.i - wb: [384] 308 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/vendor-data.txt - wb: [384] 0 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/user-data.txt.i - wb: [384] 399 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 94 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/user-data.txt - wb: [384] 94 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 5191 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 130 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 130 bytes from /var/lib/cloud/instance/cloud-config.txt May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cloud-init[DEBUG]: init will now be targeting instance id: iid-9d97397c0beec404 May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 21 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 21 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 21 bytes from /var/lib/cloud/data/instance-id May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 71 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-9d97397c0beec404/datasource - wb: [420] 71 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 71 bytes from /var/lib/cloud/instances/iid-9d97397c0beec404/datasource May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instances/iid-9d97397c0beec404/datasource (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/iid-9d97397c0beec404' May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 3011 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 3011 bytes from /etc/cloud/cloud.cfg May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[INFO]: Loaded datasource DataSourceNoCloudNet - DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/search-NoCloudNet: SUCCESS: found network data from DataSourceNoCloudNet May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] DataSourceNoCloud.py[DEBUG]: Using data from /dev/sdb May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 64 with allowed root types (,) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmp6pcrvx9e May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '/tmp/tmp6pcrvx9e'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp6pcrvx9e//network-config (quiet=False) May 21 21:41:24 ubuntu-xenial kernel: ISO 9660 Extensions: RRIP_1991A May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp6pcrvx9e//vendor-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 64 bytes from /tmp/tmp6pcrvx9e//meta-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp6pcrvx9e//meta-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 94 bytes from /tmp/tmp6pcrvx9e//user-data May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp6pcrvx9e//user-data (quiet=False) May 21 21:41:24 ubuntu-xenial kernel: ISO 9660 Extensions: Microsoft Joliet Level 3 May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'auto', '/dev/sdb', '/tmp/tmp6pcrvx9e'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Fetched {'mqueue': {'fstype': 'mqueue', 'mountpoint': '/dev/mqueue', 'opts': 'rw,relatime'}, 'hugetlbfs': {'fstype': 'hugetlbfs', 'mountpoint': '/dev/hugepages', 'opts': 'rw,relatime'}, 'systemd-1': {'fstype': 'autofs', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'opts': 'rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct'}, 'memory': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/memory', 'opts': 'rw,relatime,memory,nsroot=/'}, 'cpuset': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/cpuset', 'opts': 'rw,relatime,cpuset,nsroot=/'}, 'blkio': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/blkio', 'opts': 'rw,relatime,blkio,nsroot=/'}, 'devpts': {'fstype': 'devpts', 'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000'}, 'udev': {'fstype': 'devtmpfs', 'mountpoint': '/dev', 'opts': 'rw,nosuid,relatime,size=994076k,nr_inodes=248519,mode=755'}, 'sysfs': {'fstype': 'sysfs', 'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'fusectl': {'fstype': 'fusectl', 'mountpoint': '/sys/fs/fuse/connections', 'opts': 'rw,relatime'}, 'lxcfs': {'fstype': 'fuse.lxcfs', 'mountpoint': '/var/lib/lxcfs', 'opts': 'rw,nosuid,nodev,relatime,user_id=0,group_id=0,allow_other'}, 'net_cls,net_prio': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/net_cls,net_prio', 'opts': 'rw,relatime,net_cls,net_prio,nsroot=/'}, 'proc': {'fstype': 'proc', 'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'cgroup': {'fstype': 'cgroup', 'mountpoint': '/sys/fs/cgroup/perf_event', 'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event,nsroot=/'}, 'perf_event': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/perf_event', 'opts': 'rw,relatime,perf_event,nsroot=/'}, '/dev/sda1': {'fstype': 'ext4', 'mountpoint': '/', 'opts': 'rw,relatime,data=ordered'}, 'pids': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/pids', 'opts': 'rw,relatime,pids,nsroot=/'}, 'freezer': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/freezer', 'opts': 'rw,relatime,freezer,nsroot=/'}, 'debugfs': {'fstype': 'debugfs', 'mountpoint': '/sys/kernel/debug', 'opts': 'rw,relatime'}, 'name=systemd': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/name=systemd', 'opts': 'rw,relatime,xattr,release_agent=/lib/systemd/systemd-cgroups-agent,name=systemd,nsroot=/'}, 'tmpfs': {'fstype': 'tmpfs', 'mountpoint': '/run/lxcfs/controllers', 'opts': 'rw,relatime,size=100k,mode=700'}, 'devices': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/devices', 'opts': 'rw,relatime,devices,nsroot=/'}, 'hugetlb': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/hugetlb', 'opts': 'rw,relatime,hugetlb,nsroot=/'}, 'cpu,cpuacct': {'fstype': 'cgroup', 'mountpoint': '/run/lxcfs/controllers/cpu,cpuacct', 'opts': 'rw,relatime,cpu,cpuacct,nsroot=/'}, 'securityfs': {'fstype': 'securityfs', 'mountpoint': '/sys/kernel/security', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'pstore': {'fstype': 'pstore', 'mountpoint': '/sys/fs/pstore', 'opts': 'rw,nosuid,nodev,noexec,relatime'}} mounts from proc May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Read 3207 bytes from /proc/mounts May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] DataSourceNoCloud.py[DEBUG]: Attempting to use data from /dev/sdb May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/network-config (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/vendor-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/user-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/search-NoCloudNet: searching for network data from DataSourceNoCloudNet May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Searching for network data source in: ['DataSourceNoCloudNet', 'DataSourceConfigDriveNet', 'DataSourceOpenNebulaNet', 'DataSourceAzureNet', 'DataSourceAltCloud', 'DataSourceOVFNet', 'DataSourceMAAS', 'DataSourceGCE', 'DataSourceOpenStack', 'DataSourceCloudSigmaNet', 'DataSourceSmartOS', 'DataSourceEc2', 'DataSourceCloudStack', 'DataSourceNone'] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] __init__.py[DEBUG]: Looking for for data source in: ['NoCloud', 'ConfigDrive', 'OpenNebula', 'Azure', 'AltCloud', 'OVF', 'MAAS', 'GCE', 'OpenStack', 'CloudSigma', 'SmartOS', 'Ec2', 'CloudStack', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK'] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] stages.py[DEBUG]: no cache found May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cloud-init[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/no-net (quiet=False) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] cloud-init[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes May 21 21:41:24 ubuntu-xenial cloud-init[1077]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'init' at Sat, 21 May 2016 21:41:24 +0000. Up 19.20 seconds. May 21 21:41:24 ubuntu-xenial systemd[1]: Reached target Network. May 21 21:41:24 ubuntu-xenial systemd[1]: Starting Initial cloud-init job (metadata service crawler)... May 21 21:41:24 ubuntu-xenial systemd[1]: Started Raise network interfaces. May 21 21:41:24 ubuntu-xenial kernel: floppy0: no floppy controllers found May 21 21:41:23 ubuntu-xenial sh[914]: bound to 10.0.2.15 -- renewal in 42381 seconds. May 21 21:41:23 ubuntu-xenial dhclient[925]: bound to 10.0.2.15 -- renewal in 42381 seconds. May 21 21:41:23 ubuntu-xenial dhclient[925]: DHCPACK of 10.0.2.15 from 10.0.2.2 May 21 21:41:23 ubuntu-xenial dhclient[925]: DHCPOFFER of 10.0.2.15 from 10.0.2.2 May 21 21:41:23 ubuntu-xenial dhclient[925]: DHCPREQUEST of 10.0.2.15 on enp0s3 to 255.255.255.255 port 67 (xid=0x3d876a6a) May 21 21:41:23 ubuntu-xenial dhclient[925]: DHCPDISCOVER on enp0s3 to 255.255.255.255 port 67 interval 3 (xid=0x6a6a873d) May 21 21:41:23 ubuntu-xenial dhclient[925]: Sending on Socket/fallback May 21 21:41:23 ubuntu-xenial sh[914]: DHCPACK of 10.0.2.15 from 10.0.2.2 May 21 21:41:23 ubuntu-xenial sh[914]: DHCPOFFER of 10.0.2.15 from 10.0.2.2 May 21 21:41:23 ubuntu-xenial dhclient[925]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:23 ubuntu-xenial sh[914]: DHCPREQUEST of 10.0.2.15 on enp0s3 to 255.255.255.255 port 67 (xid=0x3d876a6a) May 21 21:41:23 ubuntu-xenial sh[914]: DHCPDISCOVER on enp0s3 to 255.255.255.255 port 67 interval 3 (xid=0x6a6a873d) May 21 21:41:23 ubuntu-xenial sh[914]: Sending on Socket/fallback May 21 21:41:23 ubuntu-xenial sh[914]: Sending on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:23 ubuntu-xenial sh[914]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:23 ubuntu-xenial dhclient[925]: Listening on LPF/enp0s3/02:40:05:c5:17:35 May 21 21:41:23 ubuntu-xenial kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp0s3: link becomes ready May 21 21:41:23 ubuntu-xenial kernel: e1000: enp0s3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX May 21 21:41:23 ubuntu-xenial kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s3: link is not ready May 21 21:41:23 ubuntu-xenial systemd[1]: Found device 82540EM Gigabit Ethernet Controller (PRO/1000 MT Desktop Adapter). May 21 21:41:23 ubuntu-xenial dhclient[925]: May 21 21:41:23 ubuntu-xenial dhclient[925]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:23 ubuntu-xenial dhclient[925]: All rights reserved. May 21 21:41:23 ubuntu-xenial dhclient[925]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:23 ubuntu-xenial sh[914]: For info, please visit https://www.isc.org/software/dhcp/ May 21 21:41:23 ubuntu-xenial sh[914]: All rights reserved. May 21 21:41:23 ubuntu-xenial sh[914]: Copyright 2004-2015 Internet Systems Consortium. May 21 21:41:23 ubuntu-xenial sh[914]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:23 ubuntu-xenial dhclient[925]: Internet Systems Consortium DHCP Client 4.3.3 May 21 21:41:23 ubuntu-xenial systemd[1]: Started ifup for enp0s3. May 21 21:41:23 ubuntu-xenial systemd[1]: Started Accounts Service. May 21 21:41:23 ubuntu-xenial systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. May 21 21:41:23 ubuntu-xenial systemd[1]: Starting Raise network interfaces... May 21 21:41:23 ubuntu-xenial systemd[1]: Reached target Network (Pre). May 21 21:41:23 ubuntu-xenial accounts-daemon[811]: started daemon version 0.6.40 May 21 21:41:23 ubuntu-xenial dbus[844]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 21 21:41:23 ubuntu-xenial polkitd[864]: started daemon version 0.105 using authority implementation `local' version `0.105' May 21 21:41:23 ubuntu-xenial systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... May 21 21:41:23 ubuntu-xenial systemd[1]: Started Login Service. May 21 21:41:23 ubuntu-xenial systemd[1]: Started LSB: MD monitoring daemon. May 21 21:41:23 ubuntu-xenial mdadm[847]: ...done. May 21 21:41:23 ubuntu-xenial systemd[1]: Started LSB: Record successful boot for GRUB. May 21 21:41:23 ubuntu-xenial systemd-logind[827]: New seat seat0. May 21 21:41:23 ubuntu-xenial mdadm[847]: * Starting MD monitoring service mdadm --monitor May 21 21:41:23 ubuntu-xenial systemd-logind[827]: Watching system buttons on /dev/input/event4 (Video Bus) May 21 21:41:23 ubuntu-xenial systemd-logind[827]: Watching system buttons on /dev/input/event1 (Sleep Button) May 21 21:41:23 ubuntu-xenial systemd-logind[827]: Watching system buttons on /dev/input/event0 (Power Button) May 21 21:41:23 ubuntu-xenial systemd[1]: Started Initial cloud-init job (pre-networking). May 21 21:41:23 ubuntu-xenial systemd[1]: Started LXD - container startup/shutdown. May 21 21:41:23 ubuntu-xenial dbus[844]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' May 21 21:41:23 ubuntu-xenial systemd[1]: Started Seed the pseudo random number generator on first boot. May 21 21:41:23 ubuntu-xenial systemd[1]: Started System Logging Service. May 21 21:41:23 ubuntu-xenial systemd[1]: Starting LSB: MD monitoring daemon... May 21 21:41:23 ubuntu-xenial dbus[844]: [system] AppArmor D-Bus mediation is enabled May 21 21:41:23 ubuntu-xenial systemd[1]: Started D-Bus System Message Bus. May 21 21:41:23 ubuntu-xenial kernel: cgroup: new mount options do not match the existing superblock, will be ignored May 21 21:41:23 ubuntu-xenial lxcfs[840]: 10: name=systemd May 21 21:41:23 ubuntu-xenial lxcfs[840]: 9: devices May 21 21:41:23 ubuntu-xenial lxcfs[840]: 8: hugetlb May 21 21:41:23 ubuntu-xenial lxcfs[840]: 7: pids May 21 21:41:23 ubuntu-xenial lxcfs[840]: 6: blkio May 21 21:41:23 ubuntu-xenial lxcfs[840]: 5: cpu,cpuacct May 21 21:41:23 ubuntu-xenial lxcfs[840]: 4: net_cls,net_prio May 21 21:41:23 ubuntu-xenial lxcfs[840]: 3: cpuset May 21 21:41:23 ubuntu-xenial lxcfs[840]: 2: memory May 21 21:41:23 ubuntu-xenial lxcfs[840]: 1: freezer May 21 21:41:23 ubuntu-xenial lxcfs[840]: hierarchies: 0: perf_event May 21 21:41:23 ubuntu-xenial systemd[1]: Started FUSE filesystem for LXC. May 21 21:41:23 ubuntu-xenial systemd[1]: Starting LSB: Record successful boot for GRUB... May 21 21:41:23 ubuntu-xenial systemd[1]: Starting System Logging Service... May 21 21:41:23 ubuntu-xenial systemd[1]: Starting Login Service... May 21 21:41:23 ubuntu-xenial acpid[815]: waiting for events: event logging is off May 21 21:41:23 ubuntu-xenial acpid[815]: 1 rule loaded May 21 21:41:23 ubuntu-xenial acpid[815]: starting up with netlink and the input layer May 21 21:41:23 ubuntu-xenial systemd[1]: Started Deferred execution scheduler. May 21 21:41:23 ubuntu-xenial systemd[1]: Started ACPI event daemon. May 21 21:41:23 ubuntu-xenial pollinate[744]: curl: (6) Could not resolve host: entropy.ubuntu.com May 21 21:41:23 ubuntu-xenial pollinate[744]: 21:41:23.444483 * Closing connection 0 May 21 21:41:23 ubuntu-xenial pollinate[744]: [139B blob data] May 21 21:41:23 ubuntu-xenial pollinate[744]: Dload Upload Total Spent Left Speed May 21 21:41:23 ubuntu-xenial pollinate[744]: May 21 21:41:23 ubuntu-xenial <13>May 21 21:41:23 pollinate[744]: WARNING: Network communication failed [0]\n % Total % Received % Xferd Average Speed Time Time Time Current May 21 21:41:23 ubuntu-xenial pollinate[819]: [432B blob data] May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Accounts Service... May 21 21:41:22 ubuntu-xenial cron[795]: (CRON) INFO (Running @reboot jobs) May 21 21:41:22 ubuntu-xenial cron[795]: (CRON) INFO (pidfile fd = 3) May 21 21:41:22 ubuntu-xenial systemd[1]: Starting LXD - container startup/shutdown... May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources May 21 21:41:22 ubuntu-xenial cloud-init[651]: Cloud-init v. 0.7.7 running 'init-local' at Sat, 21 May 2016 21:41:22 +0000. Up 16.93 seconds. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Regular background program processing daemon. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.355 seconds (0.36) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False) May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target Basic System. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] stages.py[INFO]: Applying network configuration from fallback: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'name': 'enp0s3', 'mac_address': '02:40:05:c5:17:35', 'type': 'physical'}]} May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 18 bytes from /sys/class/net/enp0s3/address May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s3/address (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 5 bytes from /sys/class/net/enp0s8/operstate May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s8/operstate (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s8/dormant (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s8/carrier (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 5 bytes from /sys/class/net/enp0s3/operstate May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s3/operstate (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s3/dormant (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/enp0s3/carrier (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] cloud-init[DEBUG]: No local datasource found May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local/search-OVF: SUCCESS: no local data found from DataSourceOVF May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Fetched {'sysfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys', 'fstype': 'sysfs'}, 'systemd-1': {'opts': 'rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'fstype': 'autofs'}, 'fusectl': {'opts': 'rw,relatime', 'mountpoint': '/sys/fs/fuse/connections', 'fstype': 'fusectl'}, 'proc': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/proc', 'fstype': 'proc'}, 'mqueue': {'opts': 'rw,relatime', 'mountpoint': '/dev/mqueue', 'fstype': 'mqueue'}, 'tmpfs': {'opts': 'ro,nosuid,nodev,noexec,mode=755', 'mountpoint': '/sys/fs/cgroup', 'fstype': 'tmpfs'}, 'devpts': {'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'mountpoint': '/dev/pts', 'fstype': 'devpts'}, 'debugfs': {'opts': 'rw,relatime', 'mountpoint': '/sys/kernel/debug', 'fstype': 'debugfs'}, '/dev/sda1': {'opts': 'rw,relatime,data=ordered', 'mountpoint': '/', 'fstype': 'ext4'}, 'hugetlbfs': {'opts': 'rw,relatime', 'mountpoint': '/dev/hugepages', 'fstype': 'hugetlbfs'}, 'udev': {'opts': 'rw,nosuid,relatime,size=994076k,nr_inodes=248519,mode=755', 'mountpoint': '/dev', 'fstype': 'devtmpfs'}, 'cgroup': {'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event,nsroot=/', 'mountpoint': '/sys/fs/cgroup/perf_event', 'fstype': 'cgroup'}, 'pstore': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/fs/pstore', 'fstype': 'pstore'}, 'securityfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/kernel/security', 'fstype': 'securityfs'}} mounts from proc May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 2077 bytes from /proc/mounts May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: dmi data /sys/class/dmi/id/product_name returned VirtualBox May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /sys/class/dmi/id/product_name May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/dmi/id/product_name (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_name May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: start: init-local/search-OVF: searching for local data from DataSourceOVF May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local/search-OpenNebula: SUCCESS: no local data found from DataSourceOpenNebula May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmpzt4ka1lv May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: RRIP_1991A May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '/tmp/tmpzt4ka1lv'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: Microsoft Joliet Level 3 May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target Sockets. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'auto', '/dev/sdb', '/tmp/tmpzt4ka1lv'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Fetched {'sysfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys', 'fstype': 'sysfs'}, 'systemd-1': {'opts': 'rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'fstype': 'autofs'}, 'fusectl': {'opts': 'rw,relatime', 'mountpoint': '/sys/fs/fuse/connections', 'fstype': 'fusectl'}, 'proc': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/proc', 'fstype': 'proc'}, 'mqueue': {'opts': 'rw,relatime', 'mountpoint': '/dev/mqueue', 'fstype': 'mqueue'}, 'tmpfs': {'opts': 'ro,nosuid,nodev,noexec,mode=755', 'mountpoint': '/sys/fs/cgroup', 'fstype': 'tmpfs'}, 'devpts': {'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'mountpoint': '/dev/pts', 'fstype': 'devpts'}, 'debugfs': {'opts': 'rw,relatime', 'mountpoint': '/sys/kernel/debug', 'fstype': 'debugfs'}, '/dev/sda1': {'opts': 'rw,relatime,data=ordered', 'mountpoint': '/', 'fstype': 'ext4'}, 'hugetlbfs': {'opts': 'rw,relatime', 'mountpoint': '/dev/hugepages', 'fstype': 'hugetlbfs'}, 'udev': {'opts': 'rw,nosuid,relatime,size=994076k,nr_inodes=248519,mode=755', 'mountpoint': '/dev', 'fstype': 'devtmpfs'}, 'cgroup': {'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event,nsroot=/', 'mountpoint': '/sys/fs/cgroup/perf_event', 'fstype': 'cgroup'}, 'pstore': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/fs/pstore', 'fstype': 'pstore'}, 'securityfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/kernel/security', 'fstype': 'securityfs'}} mounts from proc May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 2077 bytes from /proc/mounts May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False) May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on LXD - unix socket. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tLABEL=CDROM', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tLABEL=CONTEXT', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: start: init-local/search-OpenNebula: searching for local data from DataSourceOpenNebula May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local/search-ConfigDrive: SUCCESS: no local data found from DataSourceConfigDrive May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmpu_l60_cs May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on Socket activation for snappy daemon. May 21 21:41:22 ubuntu-xenial systemd[1]: Starting LXD - unix socket. May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: RRIP_1991A May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '/tmp/tmpu_l60_cs'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] openstack.py[DEBUG]: Failed reading mandatory path /tmp/tmpu_l60_cs/openstack/latest/meta_data.json due to: [Errno 2] No such file or directory: '/tmp/tmpu_l60_cs/openstack/latest/meta_data.json' May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmpu_l60_cs/openstack/latest/meta_data.json (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] openstack.py[DEBUG]: Failed reading optional path /tmp/tmpu_l60_cs/openstack/latest/network_data.json due to: [Errno 2] No such file or directory: '/tmp/tmpu_l60_cs/openstack/latest/network_data.json' May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmpu_l60_cs/openstack/latest/network_data.json (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] openstack.py[DEBUG]: Selected version 'latest' from [] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] openstack.py[DEBUG]: Unable to read openstack versions from /tmp/tmpu_l60_cs/ due to: [Errno 2] No such file or directory: '/tmp/tmpu_l60_cs/openstack' May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: Microsoft Joliet Level 3 May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on D-Bus System Message Bus Socket. May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target Timers. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Daily Cleanup of Temporary Directories. May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target Paths. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'auto', '/dev/sdb', '/tmp/tmpu_l60_cs'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Fetched {'sysfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys', 'fstype': 'sysfs'}, 'systemd-1': {'opts': 'rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'fstype': 'autofs'}, 'fusectl': {'opts': 'rw,relatime', 'mountpoint': '/sys/fs/fuse/connections', 'fstype': 'fusectl'}, 'proc': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/proc', 'fstype': 'proc'}, 'mqueue': {'opts': 'rw,relatime', 'mountpoint': '/dev/mqueue', 'fstype': 'mqueue'}, 'tmpfs': {'opts': 'ro,nosuid,nodev,noexec,mode=755', 'mountpoint': '/sys/fs/cgroup', 'fstype': 'tmpfs'}, 'devpts': {'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'mountpoint': '/dev/pts', 'fstype': 'devpts'}, 'debugfs': {'opts': 'rw,relatime', 'mountpoint': '/sys/kernel/debug', 'fstype': 'debugfs'}, '/dev/sda1': {'opts': 'rw,relatime,data=ordered', 'mountpoint': '/', 'fstype': 'ext4'}, 'hugetlbfs': {'opts': 'rw,relatime', 'mountpoint': '/dev/hugepages', 'fstype': 'hugetlbfs'}, 'udev': {'opts': 'rw,nosuid,relatime,size=994076k,nr_inodes=248519,mode=755', 'mountpoint': '/dev', 'fstype': 'devtmpfs'}, 'cgroup': {'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event,nsroot=/', 'mountpoint': '/sys/fs/cgroup/perf_event', 'fstype': 'cgroup'}, 'pstore': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/fs/pstore', 'fstype': 'pstore'}, 'securityfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/kernel/security', 'fstype': 'securityfs'}} mounts from proc May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 2077 bytes from /proc/mounts May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False) May 21 21:41:22 ubuntu-xenial systemd[1]: Started Trigger resolvconf update for networkd DNS. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tLABEL=config-2', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Socket activation for snappy daemon. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/cd1'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial pollinate[744]: <13>May 21 21:41:22 pollinate[744]: client sent challenge to [https://entropy.ubuntu.com/] May 21 21:41:22 ubuntu-xenial pollinate[761]: client sent challenge to [https://entropy.ubuntu.com/] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/cd0'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: start: init-local/search-ConfigDrive: searching for local data from DataSourceConfigDrive May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local/search-NoCloud: SUCCESS: no local data found from DataSourceNoCloud May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] DataSourceNoCloud.py[DEBUG]: DataSourceNoCloud [seed=None][dsmode=local]: not claiming datasource, dsmode=net May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] DataSourceNoCloud.py[DEBUG]: Using data from /dev/sdb May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 64 with allowed root types (,) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmp2p3rv8d7 May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '/tmp/tmp2p3rv8d7'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp2p3rv8d7//network-config (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp2p3rv8d7//vendor-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 64 bytes from /tmp/tmp2p3rv8d7//meta-data May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp2p3rv8d7//meta-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 94 bytes from /tmp/tmp2p3rv8d7//user-data May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Seed the pseudo random number generator on first boot... May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: RRIP_1991A May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp2p3rv8d7//user-data (quiet=False) May 21 21:41:22 ubuntu-xenial kernel: ISO 9660 Extensions: Microsoft Joliet Level 3 May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'auto', '/dev/sdb', '/tmp/tmp2p3rv8d7'] with allowed return codes [0] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Fetched {'sysfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys', 'fstype': 'sysfs'}, 'systemd-1': {'opts': 'rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'fstype': 'autofs'}, 'fusectl': {'opts': 'rw,relatime', 'mountpoint': '/sys/fs/fuse/connections', 'fstype': 'fusectl'}, 'proc': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/proc', 'fstype': 'proc'}, 'mqueue': {'opts': 'rw,relatime', 'mountpoint': '/dev/mqueue', 'fstype': 'mqueue'}, 'tmpfs': {'opts': 'ro,nosuid,nodev,noexec,mode=755', 'mountpoint': '/sys/fs/cgroup', 'fstype': 'tmpfs'}, 'devpts': {'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'mountpoint': '/dev/pts', 'fstype': 'devpts'}, 'debugfs': {'opts': 'rw,relatime', 'mountpoint': '/sys/kernel/debug', 'fstype': 'debugfs'}, '/dev/sda1': {'opts': 'rw,relatime,data=ordered', 'mountpoint': '/', 'fstype': 'ext4'}, 'hugetlbfs': {'opts': 'rw,relatime', 'mountpoint': '/dev/hugepages', 'fstype': 'hugetlbfs'}, 'udev': {'opts': 'rw,nosuid,relatime,size=994076k,nr_inodes=248519,mode=755', 'mountpoint': '/dev', 'fstype': 'devtmpfs'}, 'cgroup': {'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event,nsroot=/', 'mountpoint': '/sys/fs/cgroup/perf_event', 'fstype': 'cgroup'}, 'pstore': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/fs/pstore', 'fstype': 'pstore'}, 'securityfs': {'opts': 'rw,nosuid,nodev,noexec,relatime', 'mountpoint': '/sys/kernel/security', 'fstype': 'securityfs'}} mounts from proc May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 2077 bytes from /proc/mounts May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] DataSourceNoCloud.py[DEBUG]: Attempting to use data from /dev/sdb May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on ACPID Listen Socket. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Daily apt activities. May 21 21:41:22 ubuntu-xenial systemd[1]: apt-daily.timer: Adding 6h 4min 35.735516s random time. May 21 21:41:22 ubuntu-xenial systemd[1]: Started ACPI Events Check. May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on UUID daemon activation socket. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/network-config (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/vendor-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/user-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: start: init-local/search-NoCloud: searching for local data from DataSourceNoCloud May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Searching for local data source in: ['DataSourceNoCloud', 'DataSourceConfigDrive', 'DataSourceOpenNebula', 'DataSourceOVF'] May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target System Initialization. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] __init__.py[DEBUG]: Looking for for data source in: ['NoCloud', 'ConfigDrive', 'OpenNebula', 'Azure', 'AltCloud', 'OVF', 'MAAS', 'GCE', 'OpenStack', 'CloudSigma', 'SmartOS', 'Ec2', 'CloudStack', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] stages.py[DEBUG]: Using distro class May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: cache invalid in datasource: DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] stages.py[DEBUG]: cache invalid in datasource: DataSourceNoCloudNet [seed=/dev/sdb][dsmode=net] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Read 5281 bytes from /var/lib/cloud/instance/obj.pkl May 21 21:41:22 ubuntu-xenial systemd[1]: Started Update UTMP about System Boot/Shutdown. May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes May 21 21:41:22 ubuntu-xenial cloud-init[651]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'init-local' at Sat, 21 May 2016 21:41:22 +0000. Up 16.93 seconds. May 21 21:41:22 ubuntu-xenial systemd[1]: Reached target System Time Synchronized. May 21 21:41:22 ubuntu-xenial systemd[1]: Started LSB: AppArmor initialization. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Network Time Synchronization. May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 21 21:41:22 ubuntu-xenial apparmor[631]: ...done. May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.432:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.432:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.432:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/tcpdump" pid=723 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[718]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[718]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[718]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[718]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=718 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[723]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/tcpdump" pid=723 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-nesting" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-mounting" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-cgns" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=721 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[717]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-nesting" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[717]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-mounting" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[717]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-cgns" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[717]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default" pid=717 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[721]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=721 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial apparmor[631]: Skipping profile in /etc/apparmor.d/disable: usr.sbin.rsyslogd May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/lxc-start" pid=719 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial kernel: audit: type=1400 audit(1463866882.428:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/ubuntu-core-launcher" pid=720 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[719]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/lxc-start" pid=719 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial audit[720]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/ubuntu-core-launcher" pid=720 comm="apparmor_parser" May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Network Time Synchronization... May 21 21:41:22 ubuntu-xenial systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 21 21:41:22 ubuntu-xenial apparmor[631]: * Starting AppArmor profiles May 21 21:41:22 ubuntu-xenial systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Set console keymap. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Create Volatile Files and Directories. May 21 21:41:22 ubuntu-xenial systemd[1]: Started Dispatch Password Requests to Console Directory Watch. May 21 21:41:22 ubuntu-xenial loadkeys[659]: Loading /etc/console-setup/cached.kmap.gz May 21 21:41:22 ubuntu-xenial systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 21 21:41:21 ubuntu-xenial systemd[1]: Starting Set console keymap... May 21 21:41:21 ubuntu-xenial systemd[1]: Starting Initial cloud-init job (pre-networking)... May 21 21:41:21 ubuntu-xenial systemd[1]: Starting LSB: AppArmor initialization... May 21 21:41:21 ubuntu-xenial kernel: ppdev: user-space parallel port driver May 21 21:41:21 ubuntu-xenial systemd-tmpfiles[567]: [/usr/lib/tmpfiles.d/var.conf:14] Duplicate line for path "/var/log", ignoring. May 21 21:41:21 ubuntu-xenial systemd[1]: Starting Create Volatile Files and Directories... May 21 21:41:21 ubuntu-xenial systemd[1]: Reached target Local File Systems. May 21 21:41:21 ubuntu-xenial systemd[1]: Reached target Local File Systems (Pre). May 21 21:41:21 ubuntu-xenial systemd[1]: Found device /dev/ttyS0. May 21 21:41:21 ubuntu-xenial kernel: vboxguest: Successfully loaded version 5.0.18_Ubuntu (interface 0x00010004) May 21 21:41:21 ubuntu-xenial kernel: vboxguest: misc device minor 55, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) May 21 21:41:21 ubuntu-xenial kernel: input: Unspecified device as /devices/pci0000:00/0000:00:04.0/input/input6 May 21 21:41:21 ubuntu-xenial kernel: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds May 21 21:41:21 ubuntu-xenial kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A03:00/LNXVIDEO:00/input/input5 May 21 21:41:21 ubuntu-xenial kernel: ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) May 21 21:41:21 ubuntu-xenial systemd[1]: Started udev Kernel Device Manager. May 21 21:41:21 ubuntu-xenial systemd[1]: Starting udev Kernel Device Manager... May 21 21:41:21 ubuntu-xenial systemd[1]: Started Flush Journal to Persistent Storage. May 21 21:41:21 ubuntu-xenial systemd[1]: Started udev Coldplug all Devices. May 21 21:41:21 ubuntu-xenial systemd[1]: Started Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling. May 21 21:41:21 ubuntu-xenial systemd[1]: Started Load/Save Random Seed. May 21 21:41:21 ubuntu-xenial systemd[1]: Started Create Static Device Nodes in /dev. May 21 21:41:21 ubuntu-xenial systemd[1]: Started Apply Kernel Variables. May 21 21:41:21 ubuntu-xenial systemd[1]: Mounted FUSE Control File System. May 21 21:41:21 ubuntu-xenial systemd[1]: Starting Flush Journal to Persistent Storage... May 21 21:41:21 ubuntu-xenial systemd[1]: Starting Load/Save Random Seed... May 21 21:41:20 ubuntu-xenial systemd[1]: Starting udev Coldplug all Devices... May 21 21:41:20 ubuntu-xenial systemd[1]: Starting Create Static Device Nodes in /dev... May 21 21:41:20 ubuntu-xenial systemd[1]: Starting Apply Kernel Variables... May 21 21:41:20 ubuntu-xenial systemd[1]: Mounting FUSE Control File System... May 21 21:41:20 ubuntu-xenial systemd[1]: Started LVM2 metadata daemon. May 21 21:41:20 ubuntu-xenial kernel: random: nonblocking pool is initialized May 21 21:41:20 ubuntu-xenial systemd[1]: Started Nameserver information manager. May 21 21:41:20 ubuntu-xenial systemd[1]: Started Load Kernel Modules. May 21 21:41:20 ubuntu-xenial systemd[1]: Started Create list of required static device nodes for the current kernel. May 21 21:41:20 ubuntu-xenial systemd[1]: Started Uncomplicated firewall. May 21 21:41:20 ubuntu-xenial systemd[1]: Started Journal Service. May 21 21:41:20 ubuntu-xenial systemd[1]: Started Remount Root and Kernel File Systems. May 21 21:41:20 ubuntu-xenial systemd[1]: Mounted Huge Pages File System. May 21 21:41:19 ubuntu-xenial systemd[1]: Mounted POSIX Message Queue File System. May 21 21:41:19 ubuntu-xenial systemd[1]: Mounted Debug File System. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on LVM2 poll daemon socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on udev Kernel Socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Mounting Debug File System... May 21 21:41:19 ubuntu-xenial systemd[1]: Mounting Huge Pages File System... May 21 21:41:19 ubuntu-xenial systemd-modules-load[430]: Inserted module 'ib_iser' May 21 21:41:19 ubuntu-xenial kernel: iscsi: registered transport (iser) May 21 21:41:19 ubuntu-xenial systemd-modules-load[430]: Inserted module 'iscsi_tcp' May 21 21:41:19 ubuntu-xenial systemd-journald[432]: Journal started May 21 21:41:19 ubuntu-xenial kernel: iscsi: registered transport (tcp) May 21 21:41:19 ubuntu-xenial systemd[1]: Mounting POSIX Message Queue File System... May 21 21:41:19 ubuntu-xenial kernel: Loading iSCSI transport class v2.0-870. May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Journal Service... May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Load Kernel Modules... May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Create list of required static device nodes for the current kernel... May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling... May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Uncomplicated firewall... May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Nameserver information manager... May 21 21:41:19 ubuntu-xenial kernel: EXT4-fs (sda1): re-mounted. Opts: (null) May 21 21:41:19 ubuntu-xenial systemd[1]: Starting Remount Root and Kernel File Systems... May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on Journal Socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Started Forward Password Requests to Wall Directory Watch. May 21 21:41:19 ubuntu-xenial systemd[1]: Reached target Swap. May 21 21:41:19 ubuntu-xenial systemd[1]: Created slice system-serial\x2dgetty.slice. May 21 21:41:19 ubuntu-xenial systemd[1]: Reached target Slices. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on Device-mapper event daemon FIFOs. May 21 21:41:19 ubuntu-xenial systemd[1]: Created slice system-getty.slice. May 21 21:41:19 ubuntu-xenial systemd[1]: Created slice System Slice. May 21 21:41:19 ubuntu-xenial systemd[1]: Reached target Encrypted Volumes. May 21 21:41:19 ubuntu-xenial systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. May 21 21:41:19 ubuntu-xenial systemd[1]: Created slice User and Session Slice. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on Journal Socket (/dev/log). May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on Syslog Socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Reached target User and Group Name Lookups. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on LVM2 metadata daemon socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on Journal Audit Socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Listening on udev Control Socket. May 21 21:41:19 ubuntu-xenial systemd[1]: Set hostname to . May 21 21:41:19 ubuntu-xenial systemd[1]: Detected architecture x86-64. May 21 21:41:19 ubuntu-xenial systemd[1]: Detected virtualization oracle. May 21 21:41:19 ubuntu-xenial systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN) May 21 21:41:19 ubuntu-xenial kernel: EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) May 21 21:41:19 ubuntu-xenial kernel: Btrfs loaded May 21 21:41:19 ubuntu-xenial kernel: md: raid10 personality registered for level 10 May 21 21:41:19 ubuntu-xenial kernel: md: raid4 personality registered for level 4 May 21 21:41:19 ubuntu-xenial kernel: md: raid5 personality registered for level 5 May 21 21:41:19 ubuntu-xenial kernel: md: raid6 personality registered for level 6 May 21 21:41:19 ubuntu-xenial kernel: async_tx: api initialized (async) May 21 21:41:19 ubuntu-xenial kernel: avx : 28215.000 MB/sec May 21 21:41:19 ubuntu-xenial kernel: xor: automatically using best checksumming function: May 21 21:41:19 ubuntu-xenial kernel: raid6: using ssse3x2 recovery algorithm May 21 21:41:19 ubuntu-xenial kernel: raid6: .... xor() 9944 MB/s, rmw enabled May 21 21:41:19 ubuntu-xenial kernel: raid6: using algorithm sse2x4 gen() 14549 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x4 xor() 9944 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x4 gen() 14549 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x2 xor() 8164 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x2 gen() 12212 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x1 xor() 7629 MB/s May 21 21:41:19 ubuntu-xenial kernel: raid6: sse2x1 gen() 8748 MB/s May 21 21:41:19 ubuntu-xenial kernel: md: raid1 personality registered for level 1 May 21 21:41:19 ubuntu-xenial kernel: md: raid0 personality registered for level 0 May 21 21:41:19 ubuntu-xenial kernel: md: multipath personality registered for level -4 May 21 21:41:19 ubuntu-xenial kernel: md: linear personality registered for level -1 May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] Attached SCSI disk May 21 21:41:19 ubuntu-xenial kernel: sda: sda1 May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] Assuming drive cache: write through May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] Incomplete mode parameter data May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] Mode Sense: 04 00 10 00 May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] Write Protect is off May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] Attached SCSI disk May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] Assuming drive cache: write through May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] Incomplete mode parameter data May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] Mode Sense: 04 00 10 00 May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] Write Protect is off May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: [sdb] 20480 512-byte logical blocks: (10.5 MB/10.0 MiB) May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:1:0: Attached scsi generic sg1 type 0 May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB) May 21 21:41:19 ubuntu-xenial kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:1: asynchronous May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:1: Ending Domain Validation May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:1: Domain Validation skipping write tests May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:1: Beginning Domain Validation May 21 21:41:19 ubuntu-xenial kernel: scsi 2:0:1:0: Direct-Access VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:0: asynchronous May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:0: Ending Domain Validation May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:0: Domain Validation skipping write tests May 21 21:41:19 ubuntu-xenial kernel: scsi target2:0:0: Beginning Domain Validation May 21 21:41:19 ubuntu-xenial kernel: scsi 2:0:0:0: Direct-Access VBOX HARDDISK 1.0 PQ: 0 ANSI: 5 May 21 21:41:19 ubuntu-xenial kernel: floppy0: no floppy controllers found May 21 21:41:19 ubuntu-xenial kernel: scsi host2: ioc0: LSI53C1030 A0, FwRev=00000000h, Ports=1, MaxQ=256, IRQ=20 May 21 21:41:19 ubuntu-xenial kernel: ioc0: LSI53C1030 A0: Capabilities={Initiator} May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:08.0 enp0s8: renamed from eth1 May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:03.0 enp0s3: renamed from eth0 May 21 21:41:19 ubuntu-xenial kernel: mptbase: ioc0: Initiating bringup May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:08.0 eth1: Intel(R) PRO/1000 Network Connection May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:08.0 eth1: (PCI:33MHz:32-bit) 08:00:27:83:40:69 May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection May 21 21:41:19 ubuntu-xenial kernel: e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 02:40:05:c5:17:35 May 21 21:41:19 ubuntu-xenial kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 May 21 21:41:19 ubuntu-xenial kernel: AES CTR mode by8 optimization enabled May 21 21:41:19 ubuntu-xenial kernel: AVX version of gcm_enc/dec engaged. May 21 21:41:19 ubuntu-xenial kernel: Fusion MPT SPI Host driver 3.04.20 May 21 21:41:19 ubuntu-xenial kernel: e1000: Copyright (c) 1999-2006 Intel Corporation. May 21 21:41:19 ubuntu-xenial kernel: e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI May 21 21:41:19 ubuntu-xenial kernel: Copyright (c) 1999-2008 LSI Corporation May 21 21:41:19 ubuntu-xenial kernel: Fusion MPT base driver 3.04.20 May 21 21:41:19 ubuntu-xenial kernel: random: systemd-udevd urandom read with 3 bits of entropy available May 21 21:41:19 ubuntu-xenial kernel: Freeing unused kernel memory: 176K (ffff880001dd4000 - ffff880001e00000) May 21 21:41:19 ubuntu-xenial kernel: Freeing unused kernel memory: 1868K (ffff88000182d000 - ffff880001a00000) May 21 21:41:19 ubuntu-xenial kernel: Write protecting the kernel read-only data: 14336k May 21 21:41:19 ubuntu-xenial kernel: Freeing unused kernel memory: 1480K (ffffffff81f41000 - ffffffff820b3000) May 21 21:41:19 ubuntu-xenial kernel: PM: Hibernation image not present or could not be loaded. May 21 21:41:19 ubuntu-xenial kernel: EDD information not available. May 21 21:41:19 ubuntu-xenial kernel: BIOS EDD facility v0.16 2004-Jun-25, 0 devices found May 21 21:41:19 ubuntu-xenial kernel: rtc_cmos rtc_cmos: setting system clock to 2016-05-21 21:41:10 UTC (1463866870) May 21 21:41:19 ubuntu-xenial kernel: Magic number: 0:932:702 May 21 21:41:19 ubuntu-xenial kernel: evm: HMAC attrs: 0x1 May 21 21:41:19 ubuntu-xenial kernel: ima: No TPM chip found, activating TPM-bypass! May 21 21:41:19 ubuntu-xenial kernel: AppArmor: AppArmor sha1 policy hashing enabled May 21 21:41:19 ubuntu-xenial kernel: Key type encrypted registered May 21 21:41:19 ubuntu-xenial kernel: Key type trusted registered May 21 21:41:19 ubuntu-xenial kernel: zswap: loaded using pool lzo/zbud May 21 21:41:19 ubuntu-xenial kernel: Loaded X.509 cert 'Build time autogenerated kernel key: 10281ca0c0463cd0680138fff55319c9bd7d63b8' May 21 21:41:19 ubuntu-xenial kernel: Loading compiled-in X.509 certificates May 21 21:41:19 ubuntu-xenial kernel: registered taskstats version 1 May 21 21:41:19 ubuntu-xenial kernel: microcode: Microcode Update Driver: v2.01 , Peter Oruba May 21 21:41:19 ubuntu-xenial kernel: microcode: CPU3 sig=0x40661, pf=0x40, revision=0x0 May 21 21:41:19 ubuntu-xenial kernel: microcode: CPU2 sig=0x40661, pf=0x40, revision=0x0 May 21 21:41:19 ubuntu-xenial kernel: microcode: CPU1 sig=0x40661, pf=0x40, revision=0x0 May 21 21:41:19 ubuntu-xenial kernel: microcode: CPU0 sig=0x40661, pf=0x40, revision=0x0 May 21 21:41:19 ubuntu-xenial kernel: Key type dns_resolver registered May 21 21:41:19 ubuntu-xenial kernel: NET: Registered protocol family 17 May 21 21:41:19 ubuntu-xenial kernel: NET: Registered protocol family 10 May 21 21:41:19 ubuntu-xenial kernel: ledtrig-cpu: registered to indicate activity on CPUs May 21 21:41:19 ubuntu-xenial kernel: device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com May 21 21:41:19 ubuntu-xenial kernel: device-mapper: uevent: version 1.0.3 May 21 21:41:19 ubuntu-xenial kernel: i2c /dev entries driver May 21 21:41:19 ubuntu-xenial kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 May 21 21:41:19 ubuntu-xenial kernel: rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram May 21 21:41:19 ubuntu-xenial kernel: rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0 May 21 21:41:19 ubuntu-xenial kernel: mousedev: PS/2 mouse device common for all mice May 21 21:41:19 ubuntu-xenial kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 21 21:41:19 ubuntu-xenial kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 21 21:41:19 ubuntu-xenial kernel: i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f03:PS2M] at 0x60,0x64 irq 1,12 May 21 21:41:19 ubuntu-xenial kernel: uhci_hcd: USB Universal Host Controller Interface driver May 21 21:41:19 ubuntu-xenial kernel: ohci-platform: OHCI generic platform driver May 21 21:41:19 ubuntu-xenial kernel: ohci-pci: OHCI PCI platform driver May 21 21:41:19 ubuntu-xenial kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver May 21 21:41:19 ubuntu-xenial kernel: ehci-platform: EHCI generic platform driver May 21 21:41:19 ubuntu-xenial kernel: ehci-pci: EHCI PCI platform driver May 21 21:41:19 ubuntu-xenial kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver May 21 21:41:19 ubuntu-xenial kernel: PPP generic driver version 2.4.2 May 21 21:41:19 ubuntu-xenial kernel: tun: (C) 1999-2004 Max Krasnyansky May 21 21:41:19 ubuntu-xenial kernel: tun: Universal TUN/TAP device driver, 1.6 May 21 21:41:19 ubuntu-xenial kernel: libphy: Fixed MDIO Bus: probed May 21 21:41:19 ubuntu-xenial kernel: ata2: PATA max UDMA/33 cmd 0x170 ctl 0x376 bmdma 0xd008 irq 15 May 21 21:41:19 ubuntu-xenial kernel: ata1: PATA max UDMA/33 cmd 0x1f0 ctl 0x3f6 bmdma 0xd000 irq 14 May 21 21:41:19 ubuntu-xenial kernel: scsi host1: ata_piix May 21 21:41:19 ubuntu-xenial kernel: scsi host0: ata_piix May 21 21:41:19 ubuntu-xenial kernel: ata_piix 0000:00:01.1: version 2.13 May 21 21:41:19 ubuntu-xenial kernel: loop: module loaded May 21 21:41:19 ubuntu-xenial kernel: brd: module loaded May 21 21:41:19 ubuntu-xenial kernel: Linux agpgart interface v0.103 May 21 21:41:19 ubuntu-xenial kernel: 00:02: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 21 21:41:19 ubuntu-xenial kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled May 21 21:41:19 ubuntu-xenial kernel: ACPI: Battery Slot [BAT0] (battery present) May 21 21:41:19 ubuntu-xenial kernel: GHES: HEST is not enabled! May 21 21:41:19 ubuntu-xenial kernel: ACPI: Sleep Button [SLPF] May 21 21:41:19 ubuntu-xenial kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input1 May 21 21:41:19 ubuntu-xenial kernel: ACPI: Power Button [PWRF] May 21 21:41:19 ubuntu-xenial kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 May 21 21:41:19 ubuntu-xenial kernel: ACPI: AC Adapter [AC] (on-line) May 21 21:41:19 ubuntu-xenial kernel: intel_idle: does not run on family 6 model 70 May 21 21:41:19 ubuntu-xenial kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1fa56894b16, max_idle_ns: 440795239494 ns May 21 21:41:19 ubuntu-xenial kernel: tsc: Refined TSC clocksource calibration: 2195.450 MHz May 21 21:41:19 ubuntu-xenial kernel: pciehp: PCI Express Hot Plug Controller Driver version: 0.4 May 21 21:41:19 ubuntu-xenial kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5 May 21 21:41:19 ubuntu-xenial kernel: io scheduler cfq registered May 21 21:41:19 ubuntu-xenial kernel: io scheduler deadline registered (default) May 21 21:41:19 ubuntu-xenial kernel: io scheduler noop registered May 21 21:41:19 ubuntu-xenial kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 21 21:41:19 ubuntu-xenial kernel: Asymmetric key parser 'x509' registered May 21 21:41:19 ubuntu-xenial kernel: Key type asymmetric registered May 21 21:41:19 ubuntu-xenial kernel: Allocating IMA MOK and blacklist keyrings. May 21 21:41:19 ubuntu-xenial kernel: Key type big_key registered May 21 21:41:19 ubuntu-xenial kernel: fuse init (API version 7.23) May 21 21:41:19 ubuntu-xenial kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 21 21:41:19 ubuntu-xenial kernel: VFS: Disk quotas dquot_6.6.0 May 21 21:41:19 ubuntu-xenial kernel: zbud: loaded May 21 21:41:19 ubuntu-xenial kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages May 21 21:41:19 ubuntu-xenial kernel: Initialise system trusted keyring May 21 21:41:19 ubuntu-xenial kernel: audit: type=2000 audit(1463867909.334:1): initialized May 21 21:41:19 ubuntu-xenial kernel: audit: initializing netlink subsys (disabled) May 21 21:41:19 ubuntu-xenial kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) May 21 21:41:19 ubuntu-xenial kernel: Scanning for low memory corruption every 60 seconds May 21 21:41:19 ubuntu-xenial kernel: platform rtc_cmos: registered platform RTC device (no PNP device found) May 21 21:41:19 ubuntu-xenial kernel: Freeing initrd memory: 7296K (ffff8800371b0000 - ffff8800378d0000) May 21 21:41:19 ubuntu-xenial kernel: Trying to unpack rootfs image as initramfs... May 21 21:41:19 ubuntu-xenial kernel: PCI: CLS 0 bytes, default 64 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:02.0: Video device with shadowed ROM May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers May 21 21:41:19 ubuntu-xenial kernel: NET: Registered protocol family 1 May 21 21:41:19 ubuntu-xenial kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) May 21 21:41:19 ubuntu-xenial kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes) May 21 21:41:19 ubuntu-xenial kernel: TCP: Hash tables configured (established 16384 bind 16384) May 21 21:41:19 ubuntu-xenial kernel: TCP bind hash table entries: 16384 (order: 6, 262144 bytes) May 21 21:41:19 ubuntu-xenial kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes) May 21 21:41:19 ubuntu-xenial kernel: NET: Registered protocol family 2 May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: resource 7 [mem 0x7d000000-0xffdfffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 21 21:41:19 ubuntu-xenial kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 21 21:41:19 ubuntu-xenial kernel: pnp: PnP ACPI: found 3 devices May 21 21:41:19 ubuntu-xenial kernel: pnp 00:02: Plug and Play ACPI device, IDs PNP0501 (active) May 21 21:41:19 ubuntu-xenial kernel: pnp 00:01: Plug and Play ACPI device, IDs PNP0f03 (active) May 21 21:41:19 ubuntu-xenial kernel: pnp 00:00: Plug and Play ACPI device, IDs PNP0303 (active) May 21 21:41:19 ubuntu-xenial kernel: pnp: PnP ACPI init May 21 21:41:19 ubuntu-xenial kernel: AppArmor: AppArmor Filesystem Enabled May 21 21:41:19 ubuntu-xenial kernel: clocksource: Switched to clocksource kvm-clock May 21 21:41:19 ubuntu-xenial kernel: NetLabel: unlabeled traffic allowed by default May 21 21:41:19 ubuntu-xenial kernel: NetLabel: protocols = UNLABELED CIPSOv4 May 21 21:41:19 ubuntu-xenial kernel: NetLabel: domain hash size = 128 May 21 21:41:19 ubuntu-xenial kernel: NetLabel: Initializing May 21 21:41:19 ubuntu-xenial kernel: e820: reserve RAM buffer [mem 0x7cff0000-0x7fffffff] May 21 21:41:19 ubuntu-xenial kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 21 21:41:19 ubuntu-xenial kernel: PCI: pci_cache_line_size set to 64 bytes May 21 21:41:19 ubuntu-xenial kernel: PCI: Using ACPI for IRQ routing May 21 21:41:19 ubuntu-xenial kernel: usbcore: registered new device driver usb May 21 21:41:19 ubuntu-xenial kernel: usbcore: registered new interface driver hub May 21 21:41:19 ubuntu-xenial kernel: usbcore: registered new interface driver usbfs May 21 21:41:19 ubuntu-xenial kernel: ACPI: bus type USB registered May 21 21:41:19 ubuntu-xenial kernel: libata version 3.00 loaded. May 21 21:41:19 ubuntu-xenial kernel: SCSI subsystem initialized May 21 21:41:19 ubuntu-xenial kernel: vgaarb: bridge control possible 0000:00:02.0 May 21 21:41:19 ubuntu-xenial kernel: vgaarb: loaded May 21 21:41:19 ubuntu-xenial kernel: vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none May 21 21:41:19 ubuntu-xenial kernel: vgaarb: setting as boot device: PCI:0000:00:02.0 May 21 21:41:19 ubuntu-xenial kernel: ACPI: Enabled 2 GPEs in block 00 to 07 May 21 21:41:19 ubuntu-xenial kernel: ACPI: PCI Interrupt Link [LNKD] (IRQs 5 *9 10 11) May 21 21:41:19 ubuntu-xenial kernel: ACPI: PCI Interrupt Link [LNKC] (IRQs 5 9 *10 11) May 21 21:41:19 ubuntu-xenial kernel: ACPI: PCI Interrupt Link [LNKB] (IRQs 5 9 10 *11) May 21 21:41:19 ubuntu-xenial kernel: ACPI: PCI Interrupt Link [LNKA] (IRQs 5 9 10 11) *0, disabled. May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:14.0: reg 0x18: [mem 0xf0860000-0xf087ffff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:14.0: reg 0x14: [mem 0xf0840000-0xf085ffff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:14.0: reg 0x10: [io 0xd100-0xd1ff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:14.0: [1000:0030] type 00 class 0x010000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:08.0: reg 0x18: [io 0xd040-0xd047] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:08.0: reg 0x10: [mem 0xf0820000-0xf083ffff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:08.0: [8086:100e] type 00 class 0x020000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:07.0: [8086:7113] type 00 class 0x068000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:04.0: reg 0x18: [mem 0xf0800000-0xf0803fff pref] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:04.0: reg 0x14: [mem 0xf0400000-0xf07fffff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:04.0: reg 0x10: [io 0xd020-0xd03f] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:04.0: [80ee:cafe] type 00 class 0x088000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:03.0: reg 0x18: [io 0xd010-0xd017] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:03.0: reg 0x10: [mem 0xf0000000-0xf001ffff] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:02.0: reg 0x10: [mem 0xe0000000-0xe0ffffff pref] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:02.0: [80ee:beef] type 00 class 0x030000 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: reg 0x20: [io 0xd000-0xd00f] May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.1: [8086:7111] type 00 class 0x01018a May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 May 21 21:41:19 ubuntu-xenial kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: root bus resource [mem 0x7d000000-0xffdfffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 21 21:41:19 ubuntu-xenial kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 21 21:41:19 ubuntu-xenial kernel: PCI host bridge to bus 0000:00 May 21 21:41:19 ubuntu-xenial kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. May 21 21:41:19 ubuntu-xenial kernel: acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM May 21 21:41:19 ubuntu-xenial kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] May 21 21:41:19 ubuntu-xenial kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 21 21:41:19 ubuntu-xenial kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 21 21:41:19 ubuntu-xenial kernel: ACPI: Using IOAPIC for interrupt routing May 21 21:41:19 ubuntu-xenial kernel: ACPI: (supports S0 S5) May 21 21:41:19 ubuntu-xenial kernel: ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S4_] (20150930/hwxface-580) May 21 21:41:19 ubuntu-xenial kernel: ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S3_] (20150930/hwxface-580) May 21 21:41:19 ubuntu-xenial kernel: ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150930/hwxface-580) May 21 21:41:19 ubuntu-xenial kernel: ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150930/hwxface-580) May 21 21:41:19 ubuntu-xenial kernel: ACPI: Interpreter enabled May 21 21:41:19 ubuntu-xenial kernel: ACPI: Executed 1 blocks of module-level executable AML code May 21 21:41:19 ubuntu-xenial kernel: ACPI: Added _OSI(Processor Aggregator Device) May 21 21:41:19 ubuntu-xenial kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 21 21:41:19 ubuntu-xenial kernel: ACPI: Added _OSI(Processor Device) May 21 21:41:19 ubuntu-xenial kernel: ACPI: Added _OSI(Module Device) May 21 21:41:19 ubuntu-xenial kernel: PCI: Using configuration type 1 for base access May 21 21:41:19 ubuntu-xenial kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 21 21:41:19 ubuntu-xenial kernel: ACPI: bus type PCI registered May 21 21:41:19 ubuntu-xenial kernel: PCCT header not found. May 21 21:41:19 ubuntu-xenial kernel: cpuidle: using governor menu May 21 21:41:19 ubuntu-xenial kernel: cpuidle: using governor ladder May 21 21:41:19 ubuntu-xenial kernel: NET: Registered protocol family 16 May 21 21:41:19 ubuntu-xenial kernel: RTC time: 21:41:04, date: 05/21/16 May 21 21:41:19 ubuntu-xenial kernel: pinctrl core: initialized pinctrl subsystem May 21 21:41:19 ubuntu-xenial kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns May 21 21:41:19 ubuntu-xenial kernel: evm: security.capability May 21 21:41:19 ubuntu-xenial kernel: evm: security.ima May 21 21:41:19 ubuntu-xenial kernel: evm: security.SMACK64MMAP May 21 21:41:19 ubuntu-xenial kernel: evm: security.SMACK64TRANSMUTE May 21 21:41:19 ubuntu-xenial kernel: evm: security.SMACK64EXEC May 21 21:41:19 ubuntu-xenial kernel: evm: security.SMACK64 May 21 21:41:19 ubuntu-xenial kernel: evm: security.selinux May 21 21:41:19 ubuntu-xenial kernel: devtmpfs: initialized May 21 21:41:19 ubuntu-xenial kernel: smpboot: Total of 4 processors activated (17559.40 BogoMIPS) May 21 21:41:19 ubuntu-xenial kernel: x86: Booted up 1 node, 4 CPUs May 21 21:41:19 ubuntu-xenial kernel: mce: CPU supports 0 MCE banks May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: cpu 3, msr 0:7cfe70c1, secondary cpu clock May 21 21:41:19 ubuntu-xenial kernel: #3 May 21 21:41:19 ubuntu-xenial kernel: mce: CPU supports 0 MCE banks May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: cpu 2, msr 0:7cfe7081, secondary cpu clock May 21 21:41:19 ubuntu-xenial kernel: #2 May 21 21:41:19 ubuntu-xenial kernel: mce: CPU supports 0 MCE banks May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: cpu 1, msr 0:7cfe7041, secondary cpu clock May 21 21:41:19 ubuntu-xenial kernel: .... node #0, CPUs: #1 May 21 21:41:19 ubuntu-xenial kernel: x86: Booting SMP configuration: May 21 21:41:19 ubuntu-xenial kernel: KVM setup paravirtual spinlock May 21 21:41:19 ubuntu-xenial kernel: Performance Events: unsupported p6 CPU model 70 no PMU driver, software events only. May 21 21:41:19 ubuntu-xenial kernel: smpboot: CPU0: Intel(R) Core(TM) i7-4770HQ CPU @ 2.20GHz (family: 0x6, model: 0x46, stepping: 0x1) May 21 21:41:19 ubuntu-xenial kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 21 21:41:19 ubuntu-xenial kernel: smpboot: APIC(0) Converting physical 0 to logical package 0 May 21 21:41:19 ubuntu-xenial kernel: smpboot: Max logical packages: 1 May 21 21:41:19 ubuntu-xenial kernel: ftrace: allocating 31906 entries in 125 pages May 21 21:41:19 ubuntu-xenial kernel: Freeing SMP alternatives memory: 28K (ffffffff820b3000 - ffffffff820ba000) May 21 21:41:19 ubuntu-xenial kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 May 21 21:41:19 ubuntu-xenial kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 May 21 21:41:19 ubuntu-xenial kernel: mce: CPU supports 0 MCE banks May 21 21:41:19 ubuntu-xenial kernel: CPU: Processor Core ID: 0 May 21 21:41:19 ubuntu-xenial kernel: CPU: Physical Processor ID: 0 May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys pids May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys hugetlb May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys net_prio May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys perf_event May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys net_cls May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys freezer May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys devices May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys memory May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys io May 21 21:41:19 ubuntu-xenial kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) May 21 21:41:19 ubuntu-xenial kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) May 21 21:41:19 ubuntu-xenial kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) May 21 21:41:19 ubuntu-xenial kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) May 21 21:41:19 ubuntu-xenial kernel: AppArmor: AppArmor initialized May 21 21:41:19 ubuntu-xenial kernel: Yama: becoming mindful. May 21 21:41:19 ubuntu-xenial kernel: Security Framework initialized May 21 21:41:19 ubuntu-xenial kernel: ACPI: 2 ACPI AML tables successfully acquired and loaded May 21 21:41:19 ubuntu-xenial kernel: ACPI: Core revision 20150930 May 21 21:41:19 ubuntu-xenial kernel: pid_max: default: 32768 minimum: 301 May 21 21:41:19 ubuntu-xenial kernel: Calibrating delay loop (skipped) preset value.. 4389.85 BogoMIPS (lpj=8779704) May 21 21:41:19 ubuntu-xenial kernel: tsc: Detected 2194.926 MHz processor May 21 21:41:19 ubuntu-xenial kernel: console [ttyS0] enabled May 21 21:41:19 ubuntu-xenial kernel: console [tty1] enabled May 21 21:41:19 ubuntu-xenial kernel: Console: colour VGA+ 80x25 May 21 21:41:19 ubuntu-xenial kernel: NR_IRQS:16640 nr_irqs:456 16 May 21 21:41:19 ubuntu-xenial kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 May 21 21:41:19 ubuntu-xenial kernel: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. May 21 21:41:19 ubuntu-xenial kernel: Build-time adjustment of leaf fanout to 64. May 21 21:41:19 ubuntu-xenial kernel: Hierarchical RCU implementation. May 21 21:41:19 ubuntu-xenial kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 21 21:41:19 ubuntu-xenial kernel: Memory: 1988124K/2047544K available (8359K kernel code, 1278K rwdata, 3920K rodata, 1480K init, 1292K bss, 59420K reserved, 0K cma-reserved) May 21 21:41:19 ubuntu-xenial kernel: Calgary: Unable to locate Rio Grande table in EBDA - bailing! May 21 21:41:19 ubuntu-xenial kernel: Calgary: detecting Calgary via BIOS EBDA area May 21 21:41:19 ubuntu-xenial kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) May 21 21:41:19 ubuntu-xenial kernel: Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-22-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0 May 21 21:41:19 ubuntu-xenial kernel: Policy zone: DMA32 May 21 21:41:19 ubuntu-xenial kernel: Built 1 zonelists in Node order, mobility grouping on. Total pages: 503865 May 21 21:41:19 ubuntu-xenial kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes) May 21 21:41:19 ubuntu-xenial kernel: pcpu-alloc: [0] 0 1 2 3 May 21 21:41:19 ubuntu-xenial kernel: pcpu-alloc: s98008 r8192 d28968 u524288 alloc=1*2097152 May 21 21:41:19 ubuntu-xenial kernel: PERCPU: Embedded 33 pages/cpu @ffff88007cc00000 s98008 r8192 d28968 u524288 May 21 21:41:19 ubuntu-xenial kernel: setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:4 nr_node_ids:1 May 21 21:41:19 ubuntu-xenial kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns May 21 21:41:19 ubuntu-xenial kernel: Booting paravirtualized kernel on KVM May 21 21:41:19 ubuntu-xenial kernel: e820: [mem 0x7d000000-0xfffbffff] available for PCI devices May 21 21:41:19 ubuntu-xenial kernel: PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] May 21 21:41:19 ubuntu-xenial kernel: PM: Registered nosave memory: [mem 0x000a0000-0x000effff] May 21 21:41:19 ubuntu-xenial kernel: PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] May 21 21:41:19 ubuntu-xenial kernel: PM: Registered nosave memory: [mem 0x00000000-0x00000fff] May 21 21:41:19 ubuntu-xenial kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 21 21:41:19 ubuntu-xenial kernel: Using ACPI (MADT) for SMP configuration information May 21 21:41:19 ubuntu-xenial kernel: ACPI: IRQ9 used by override. May 21 21:41:19 ubuntu-xenial kernel: ACPI: IRQ0 used by override. May 21 21:41:19 ubuntu-xenial kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 21 21:41:19 ubuntu-xenial kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 21 21:41:19 ubuntu-xenial kernel: IOAPIC[0]: apic_id 4, version 17, address 0xfec00000, GSI 0-23 May 21 21:41:19 ubuntu-xenial kernel: ACPI: Local APIC address 0xfee00000 May 21 21:41:19 ubuntu-xenial kernel: ACPI: PM-Timer IO Port: 0x4008 May 21 21:41:19 ubuntu-xenial kernel: DMA32 zone: 507888 pages, LIFO batch:31 May 21 21:41:19 ubuntu-xenial kernel: DMA32 zone: 7936 pages used for memmap May 21 21:41:19 ubuntu-xenial kernel: DMA zone: 3998 pages, LIFO batch:0 May 21 21:41:19 ubuntu-xenial kernel: DMA zone: 21 pages reserved May 21 21:41:19 ubuntu-xenial kernel: DMA zone: 64 pages used for memmap May 21 21:41:19 ubuntu-xenial kernel: On node 0 totalpages: 511886 May 21 21:41:19 ubuntu-xenial kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007cfeffff] May 21 21:41:19 ubuntu-xenial kernel: node 0: [mem 0x0000000000100000-0x000000007cfeffff] May 21 21:41:19 ubuntu-xenial kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 21 21:41:19 ubuntu-xenial kernel: Early memory node ranges May 21 21:41:19 ubuntu-xenial kernel: Movable zone start for each node May 21 21:41:19 ubuntu-xenial kernel: Device empty May 21 21:41:19 ubuntu-xenial kernel: Normal empty May 21 21:41:19 ubuntu-xenial kernel: DMA32 [mem 0x0000000001000000-0x000000007cfeffff] May 21 21:41:19 ubuntu-xenial kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 21 21:41:19 ubuntu-xenial kernel: Zone ranges: May 21 21:41:19 ubuntu-xenial kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: using sched offset of 1044206518129 cycles May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: cpu 0, msr 0:7cfe7001, primary cpu clock May 21 21:41:19 ubuntu-xenial kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 21 21:41:19 ubuntu-xenial kernel: NODE_DATA(0) allocated [mem 0x7cfeb000-0x7cfeffff] May 21 21:41:19 ubuntu-xenial kernel: Faking a node at [mem 0x0000000000000000-0x000000007cfeffff] May 21 21:41:19 ubuntu-xenial kernel: No NUMA configuration found May 21 21:41:19 ubuntu-xenial kernel: ACPI: Local APIC address 0xfee00000 May 21 21:41:19 ubuntu-xenial kernel: ACPI: SSDT 0x000000007CFF02B0 0001CC (v01 VBOX VBOXCPUT 00000002 INTL 20160212) May 21 21:41:19 ubuntu-xenial kernel: ACPI: APIC 0x000000007CFF0240 00006C (v02 VBOX VBOXAPIC 00000001 ASL 00000061) May 21 21:41:19 ubuntu-xenial kernel: ACPI: FACS 0x000000007CFF0200 000040 May 21 21:41:19 ubuntu-xenial kernel: ACPI: FACS 0x000000007CFF0200 000040 May 21 21:41:19 ubuntu-xenial kernel: ACPI: DSDT 0x000000007CFF0480 002106 (v01 VBOX VBOXBIOS 00000002 INTL 20160212) May 21 21:41:19 ubuntu-xenial kernel: ACPI: FACP 0x000000007CFF00F0 0000F4 (v04 VBOX VBOXFACP 00000001 ASL 00000061) May 21 21:41:19 ubuntu-xenial kernel: ACPI: XSDT 0x000000007CFF0030 00003C (v01 VBOX VBOXXSDT 00000001 ASL 00000061) May 21 21:41:19 ubuntu-xenial kernel: ACPI: RSDP 0x00000000000E0000 000024 (v02 VBOX ) May 21 21:41:19 ubuntu-xenial kernel: ACPI: Early table checksum verification disabled May 21 21:41:19 ubuntu-xenial kernel: RAMDISK: [mem 0x371b0000-0x378cffff] May 21 21:41:19 ubuntu-xenial kernel: BRK [0x02203000, 0x02203fff] PGTABLE May 21 21:41:19 ubuntu-xenial kernel: BRK [0x02202000, 0x02202fff] PGTABLE May 21 21:41:19 ubuntu-xenial kernel: BRK [0x02201000, 0x02201fff] PGTABLE May 21 21:41:19 ubuntu-xenial kernel: BRK [0x02200000, 0x02200fff] PGTABLE May 21 21:41:19 ubuntu-xenial kernel: BRK [0x021ff000, 0x021fffff] PGTABLE May 21 21:41:19 ubuntu-xenial kernel: Base memory trampoline at [ffff880000099000] 99000 size 24576 May 21 21:41:19 ubuntu-xenial kernel: Scanning 1 areas for low memory corruption May 21 21:41:19 ubuntu-xenial kernel: found SMP MP-table at [mem 0x0009fff0-0x0009ffff] mapped at [ffff88000009fff0] May 21 21:41:19 ubuntu-xenial kernel: CPU MTRRs all blank - virtualized system. May 21 21:41:19 ubuntu-xenial kernel: MTRR: Disabled May 21 21:41:19 ubuntu-xenial kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT May 21 21:41:19 ubuntu-xenial kernel: MTRR variable ranges disabled: May 21 21:41:19 ubuntu-xenial kernel: MTRR default type: uncachable May 21 21:41:19 ubuntu-xenial kernel: e820: last_pfn = 0x7cff0 max_arch_pfn = 0x400000000 May 21 21:41:19 ubuntu-xenial kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 21 21:41:19 ubuntu-xenial kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 21 21:41:19 ubuntu-xenial kernel: Hypervisor detected: KVM May 21 21:41:19 ubuntu-xenial kernel: DMI: innotek GmbH VirtualBox/VirtualBox, BIOS VirtualBox 12/01/2006 May 21 21:41:19 ubuntu-xenial kernel: SMBIOS 2.5 present. May 21 21:41:19 ubuntu-xenial kernel: NX (Execute Disable) protection: active May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x000000007cff0000-0x000000007cffffff] ACPI data May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007cfeffff] usable May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 21 21:41:19 ubuntu-xenial kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 21 21:41:19 ubuntu-xenial kernel: e820: BIOS-provided physical RAM map: May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: Using 'lazy' FPU context switches. May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers' May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers' May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers' May 21 21:41:19 ubuntu-xenial kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 21 21:41:19 ubuntu-xenial kernel: Centaur CentaurHauls May 21 21:41:19 ubuntu-xenial kernel: AMD AuthenticAMD May 21 21:41:19 ubuntu-xenial kernel: Intel GenuineIntel May 21 21:41:19 ubuntu-xenial kernel: KERNEL supported cpus: May 21 21:41:19 ubuntu-xenial kernel: Command line: BOOT_IMAGE=/boot/vmlinuz-4.4.0-22-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0 May 21 21:41:19 ubuntu-xenial kernel: Linux version 4.4.0-22-generic (buildd@lgw01-41) (gcc version 5.3.1 20160413 (Ubuntu 5.3.1-14ubuntu2) ) #40-Ubuntu SMP Thu May 12 22:03:46 UTC 2016 (Ubuntu 4.4.0-22.40-generic 4.4.8) May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys cpuacct May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys cpu May 21 21:41:19 ubuntu-xenial kernel: Initializing cgroup subsys cpuset May 21 21:41:19 ubuntu-xenial systemd-journald[432]: Runtime journal (/run/log/journal/) is 2.4M, max 19.5M, 17.0M free.