Created
August 17, 2019 20:58
-
-
Save zimmertr/2b624461293e456b79b24ec873fadbc7 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
root@Eris:/home/debian# cat /var/log/cloud-init.log | |
2019-08-17 20:26:58,178 - util.py[DEBUG]: Cloud-init v. 18.3 running 'init-local' at Sat, 17 Aug 2019 20:26:58 +0000. Up 4.36 seconds. | |
2019-08-17 20:26:58,180 - main.py[DEBUG]: No kernel command line url found. | |
2019-08-17 20:26:58,180 - main.py[DEBUG]: Closing stdin. | |
2019-08-17 20:26:58,197 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2019-08-17 20:26:58,201 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4 | |
2019-08-17 20:26:58,202 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished | |
2019-08-17 20:26:58,202 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net | |
2019-08-17 20:26:58,205 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] | |
2019-08-17 20:26:58,205 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2019-08-17 20:26:58,206 - stages.py[DEBUG]: no cache found | |
2019-08-17 20:26:58,206 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found | |
2019-08-17 20:26:58,206 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2019-08-17 20:26:58,217 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:26:58,220 - __init__.py[DEBUG]: Looking for data source in: ['NoCloud', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] | |
2019-08-17 20:26:58,246 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceNoCloud'] | |
2019-08-17 20:26:58,246 - handlers.py[DEBUG]: start: init-local/search-NoCloud: searching for local data from DataSourceNoCloud | |
2019-08-17 20:26:58,246 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceNoCloud.DataSourceNoCloud'> | |
2019-08-17 20:26:58,248 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:58,303 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:58,344 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:58,398 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) | |
2019-08-17 20:26:58,410 - util.py[DEBUG]: Read 168 bytes from /proc/1/environ | |
2019-08-17 20:26:58,411 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) | |
2019-08-17 20:26:58,412 - util.py[DEBUG]: Read 1015 bytes from /proc/self/status | |
2019-08-17 20:26:58,414 - util.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_serial | |
2019-08-17 20:26:58,416 - util.py[DEBUG]: Reading from /sys/class/dmi/id/product_serial (quiet=False) | |
2019-08-17 20:26:58,416 - util.py[DEBUG]: Read 1 bytes from /sys/class/dmi/id/product_serial | |
2019-08-17 20:26:58,417 - util.py[DEBUG]: dmi data /sys/class/dmi/id/product_serial returned | |
2019-08-17 20:26:58,420 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) | |
2019-08-17 20:26:58,420 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/user-data (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/vendor-data (quiet=False) | |
2019-08-17 20:26:58,421 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/network-config (quiet=False) | |
2019-08-17 20:26:58,422 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2019-08-17 20:26:58,497 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2019-08-17 20:26:58,599 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2019-08-17 20:26:58,738 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2019-08-17 20:26:58,846 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2019-08-17 20:26:58,938 - DataSourceNoCloud.py[DEBUG]: Attempting to use data from /dev/sr0 | |
2019-08-17 20:26:59,056 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False) | |
2019-08-17 20:26:59,066 - util.py[DEBUG]: Read 2051 bytes from /proc/mounts | |
2019-08-17 20:26:59,069 - util.py[DEBUG]: Fetched {'sysfs': {'fstype': 'sysfs', 'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'proc': {'fstype': 'proc', 'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'udev': {'fstype': 'devtmpfs', 'mountpoint': '/dev', 'opts': 'rw,nosuid,relatime,size=5110168k,nr_inodes=1277542,mode=755'}, 'devpts': {'fstype': 'devpts', 'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000'}, 'tmpfs': {'fstype': 'tmpfs', 'mountpoint': '/sys/fs/cgroup', 'opts': 'ro,nosuid,nodev,noexec,mode=755'}, '/dev/sda1': {'fstype': 'ext4', 'mountpoint': '/', 'opts': 'rw,relatime,errors=remount-ro'}, 'securityfs': {'fstype': 'securityfs', 'mountpoint': '/sys/kernel/security', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'cgroup2': {'fstype': 'cgroup2', 'mountpoint': '/sys/fs/cgroup/unified', 'opts': 'rw,nosuid,nodev,noexec,relatime,nsdelegate'}, 'cgroup': {'fstype': 'cgroup', 'mountpoint': '/sys/fs/cgroup/blkio', 'opts': 'rw,nosuid,nodev,noexec,relatime,blkio'}, 'pstore': {'fstype': 'pstore', 'mountpoint': '/sys/fs/pstore', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'bpf': {'fstype': 'bpf', 'mountpoint': '/sys/fs/bpf', 'opts': 'rw,nosuid,nodev,noexec,relatime,mode=700'}, 'systemd-1': {'fstype': 'autofs', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'opts': 'rw,relatime,fd=28,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=8802'}, 'mqueue': {'fstype': 'mqueue', 'mountpoint': '/dev/mqueue', 'opts': 'rw,relatime'}, 'debugfs': {'fstype': 'debugfs', 'mountpoint': '/sys/kernel/debug', 'opts': 'rw,relatime'}, 'hugetlbfs': {'fstype': 'hugetlbfs', 'mountpoint': '/dev/hugepages', 'opts': 'rw,relatime,pagesize=2M'}} mounts from proc | |
2019-08-17 20:26:59,082 - util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'auto', '/dev/sr0', '/run/cloud-init/tmp/tmp7g6l0g2q'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:59,158 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmp7g6l0g2q//user-data (quiet=False) | |
2019-08-17 20:26:59,169 - util.py[DEBUG]: Read 570 bytes from /run/cloud-init/tmp/tmp7g6l0g2q//user-data | |
2019-08-17 20:26:59,169 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmp7g6l0g2q//meta-data (quiet=False) | |
2019-08-17 20:26:59,170 - util.py[DEBUG]: Read 54 bytes from /run/cloud-init/tmp/tmp7g6l0g2q//meta-data | |
2019-08-17 20:26:59,170 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmp7g6l0g2q//vendor-data (quiet=False) | |
2019-08-17 20:26:59,170 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmp7g6l0g2q//network-config (quiet=False) | |
2019-08-17 20:26:59,171 - util.py[DEBUG]: Read 330 bytes from /run/cloud-init/tmp/tmp7g6l0g2q//network-config | |
2019-08-17 20:26:59,171 - util.py[DEBUG]: Running command ['umount', '/run/cloud-init/tmp/tmp7g6l0g2q'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:59,234 - util.py[DEBUG]: Attempting to load yaml from string of length 54 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,261 - util.py[DEBUG]: Attempting to load yaml from string of length 330 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,266 - DataSourceNoCloud.py[DEBUG]: Using data from /dev/sr0 | |
2019-08-17 20:26:59,270 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) | |
2019-08-17 20:26:59,271 - util.py[DEBUG]: Read 136 bytes from /etc/hosts | |
2019-08-17 20:26:59,291 - handlers.py[DEBUG]: finish: init-local/search-NoCloud: SUCCESS: found local data from DataSourceNoCloud | |
2019-08-17 20:26:59,291 - stages.py[INFO]: Loaded datasource DataSourceNoCloud - DataSourceNoCloud [seed=/dev/sr0][dsmode=net] | |
2019-08-17 20:26:59,300 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2019-08-17 20:26:59,300 - util.py[DEBUG]: Read 2607 bytes from /etc/cloud/cloud.cfg | |
2019-08-17 20:26:59,300 - util.py[DEBUG]: Attempting to load yaml from string of length 2607 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,317 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2019-08-17 20:26:59,318 - util.py[DEBUG]: Read 172 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2019-08-17 20:26:59,318 - util.py[DEBUG]: Attempting to load yaml from string of length 172 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,320 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2019-08-17 20:26:59,321 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2019-08-17 20:26:59,321 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,326 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) | |
2019-08-17 20:26:59,326 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg | |
2019-08-17 20:26:59,326 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,328 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2019-08-17 20:26:59,328 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg | |
2019-08-17 20:26:59,328 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,330 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,331 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2019-08-17 20:26:59,337 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2019-08-17 20:26:59,337 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce' | |
2019-08-17 20:26:59,348 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/datasource (quiet=False) | |
2019-08-17 20:26:59,348 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/datasource - wb: [644] 65 bytes | |
2019-08-17 20:26:59,349 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 65 bytes | |
2019-08-17 20:26:59,350 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2019-08-17 20:26:59,350 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID | |
2019-08-17 20:26:59,350 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 41 bytes | |
2019-08-17 20:26:59,350 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 41 bytes | |
2019-08-17 20:26:59,351 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes | |
2019-08-17 20:26:59,355 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 5664 bytes | |
2019-08-17 20:26:59,356 - main.py[DEBUG]: [local] init will now be targeting instance id: 292d95f4c60b81cc9ce0125a208d51894c93e2ce. new=True | |
2019-08-17 20:26:59,357 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2019-08-17 20:26:59,358 - util.py[DEBUG]: Read 2607 bytes from /etc/cloud/cloud.cfg | |
2019-08-17 20:26:59,358 - util.py[DEBUG]: Attempting to load yaml from string of length 2607 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,369 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2019-08-17 20:26:59,370 - util.py[DEBUG]: Read 172 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2019-08-17 20:26:59,370 - util.py[DEBUG]: Attempting to load yaml from string of length 172 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,371 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2019-08-17 20:26:59,371 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2019-08-17 20:26:59,371 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,375 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) | |
2019-08-17 20:26:59,375 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg | |
2019-08-17 20:26:59,375 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,377 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2019-08-17 20:26:59,377 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg | |
2019-08-17 20:26:59,377 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,377 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:26:59,377 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2019-08-17 20:26:59,379 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:26:59,390 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'mac_address': 'ae:63:41:e3:3d:48', 'subnets': [{'type': 'static', 'address': '192.168.40.101', 'netmask': '255.255.255.0', 'gateway': '192.168.40.1'}]}, {'type': 'nameserver', 'address': ['192.168.1.100'], 'search': ['sol.milkyway']}]} | |
2019-08-17 20:26:59,392 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) | |
2019-08-17 20:26:59,392 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device | |
2019-08-17 20:26:59,392 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) | |
2019-08-17 20:26:59,392 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type | |
2019-08-17 20:26:59,392 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) | |
2019-08-17 20:26:59,393 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent | |
2019-08-17 20:26:59,393 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2019-08-17 20:26:59,393 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2019-08-17 20:26:59,393 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) | |
2019-08-17 20:26:59,393 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False) | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False) | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/eth0/uevent | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) | |
2019-08-17 20:26:59,394 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device | |
2019-08-17 20:26:59,395 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) | |
2019-08-17 20:26:59,395 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate | |
2019-08-17 20:26:59,395 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False) | |
2019-08-17 20:26:59,395 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/eth0/operstate | |
2019-08-17 20:26:59,396 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:59,434 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:26:59,484 - __init__.py[DEBUG]: no work necessary for renaming of [['ae:63:41:e3:3d:48', 'eth0', 'virtio_net', '0x0001']] | |
2019-08-17 20:26:59,501 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'mac_address': 'ae:63:41:e3:3d:48', 'subnets': [{'type': 'static', 'address': '192.168.40.101', 'netmask': '255.255.255.0', 'gateway': '192.168.40.1'}]}, {'type': 'nameserver', 'address': ['192.168.1.100'], 'search': ['sol.milkyway']}]} | |
2019-08-17 20:26:59,508 - __init__.py[DEBUG]: Selected renderer 'eni' from priority list: None | |
2019-08-17 20:26:59,535 - util.py[DEBUG]: Writing to /etc/network/interfaces.d/50-cloud-init.cfg - wb: [644] 486 bytes | |
2019-08-17 20:26:59,544 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [644] 96 bytes | |
2019-08-17 20:26:59,545 - main.py[DEBUG]: [local] Exiting. datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net] not in local mode. | |
2019-08-17 20:26:59,557 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2019-08-17 20:26:59,558 - util.py[DEBUG]: Read 11 bytes from /proc/uptime | |
2019-08-17 20:26:59,558 - util.py[DEBUG]: cloud-init mode 'init' took 1.661 seconds (1.66) | |
2019-08-17 20:26:59,562 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources | |
2019-08-17 20:27:02,534 - util.py[DEBUG]: Cloud-init v. 18.3 running 'init' at Sat, 17 Aug 2019 20:27:02 +0000. Up 8.74 seconds. | |
2019-08-17 20:27:02,536 - main.py[DEBUG]: No kernel command line url found. | |
2019-08-17 20:27:02,536 - main.py[DEBUG]: Closing stdin. | |
2019-08-17 20:27:02,548 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2019-08-17 20:27:02,552 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4 | |
2019-08-17 20:27:02,556 - util.py[DEBUG]: Running command ['ip', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:02,619 - util.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:02,667 - util.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2019-08-17 20:27:02,717 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. | |
2019-08-17 20:27:02,724 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. | |
2019-08-17 20:27:02,731 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] | |
2019-08-17 20:27:02,733 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2019-08-17 20:27:02,734 - util.py[DEBUG]: Read 5664 bytes from /var/lib/cloud/instance/obj.pkl | |
2019-08-17 20:27:02,783 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) | |
2019-08-17 20:27:02,783 - util.py[DEBUG]: Read 41 bytes from /run/cloud-init/.instance-id | |
2019-08-17 20:27:02,785 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net] | |
2019-08-17 20:27:02,785 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net] | |
2019-08-17 20:27:02,787 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2019-08-17 20:27:02,787 - util.py[DEBUG]: Read 2607 bytes from /etc/cloud/cloud.cfg | |
2019-08-17 20:27:02,787 - util.py[DEBUG]: Attempting to load yaml from string of length 2607 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,810 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2019-08-17 20:27:02,810 - util.py[DEBUG]: Read 172 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2019-08-17 20:27:02,811 - util.py[DEBUG]: Attempting to load yaml from string of length 172 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,812 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2019-08-17 20:27:02,812 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2019-08-17 20:27:02,812 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,817 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) | |
2019-08-17 20:27:02,817 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg | |
2019-08-17 20:27:02,817 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,819 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2019-08-17 20:27:02,819 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg | |
2019-08-17 20:27:02,819 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,821 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,821 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2019-08-17 20:27:02,822 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2019-08-17 20:27:02,822 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce' | |
2019-08-17 20:27:02,825 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/datasource (quiet=False) | |
2019-08-17 20:27:02,825 - util.py[DEBUG]: Read 65 bytes from /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/datasource | |
2019-08-17 20:27:02,826 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/datasource - wb: [644] 65 bytes | |
2019-08-17 20:27:02,826 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 65 bytes | |
2019-08-17 20:27:02,827 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2019-08-17 20:27:02,827 - util.py[DEBUG]: Read 41 bytes from /var/lib/cloud/data/instance-id | |
2019-08-17 20:27:02,827 - stages.py[DEBUG]: previous iid found to be 292d95f4c60b81cc9ce0125a208d51894c93e2ce | |
2019-08-17 20:27:02,827 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 41 bytes | |
2019-08-17 20:27:02,828 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 41 bytes | |
2019-08-17 20:27:02,828 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 41 bytes | |
2019-08-17 20:27:02,831 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 5754 bytes | |
2019-08-17 20:27:02,831 - main.py[DEBUG]: [net] init will now be targeting instance id: 292d95f4c60b81cc9ce0125a208d51894c93e2ce. new=False | |
2019-08-17 20:27:02,832 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2019-08-17 20:27:02,832 - util.py[DEBUG]: Read 2607 bytes from /etc/cloud/cloud.cfg | |
2019-08-17 20:27:02,832 - util.py[DEBUG]: Attempting to load yaml from string of length 2607 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,845 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2019-08-17 20:27:02,846 - util.py[DEBUG]: Read 172 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2019-08-17 20:27:02,846 - util.py[DEBUG]: Attempting to load yaml from string of length 172 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,847 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2019-08-17 20:27:02,847 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2019-08-17 20:27:02,847 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,851 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) | |
2019-08-17 20:27:02,851 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg | |
2019-08-17 20:27:02,851 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,852 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2019-08-17 20:27:02,852 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg | |
2019-08-17 20:27:02,853 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,853 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:02,853 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2019-08-17 20:27:02,863 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'mac_address': 'ae:63:41:e3:3d:48', 'subnets': [{'type': 'static', 'address': '192.168.40.101', 'netmask': '255.255.255.0', 'gateway': '192.168.40.1'}]}, {'type': 'nameserver', 'address': ['192.168.1.100'], 'search': ['sol.milkyway']}]} | |
2019-08-17 20:27:02,864 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:27:02,868 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) | |
2019-08-17 20:27:02,868 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device | |
2019-08-17 20:27:02,868 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2019-08-17 20:27:02,869 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False) | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False) | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/eth0/uevent | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) | |
2019-08-17 20:27:02,870 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device | |
2019-08-17 20:27:02,871 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) | |
2019-08-17 20:27:02,871 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate | |
2019-08-17 20:27:02,871 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False) | |
2019-08-17 20:27:02,871 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate | |
2019-08-17 20:27:02,872 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:02,901 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:02,948 - __init__.py[DEBUG]: no work necessary for renaming of [['ae:63:41:e3:3d:48', 'eth0', 'virtio_net', '0x0001']] | |
2019-08-17 20:27:02,964 - stages.py[DEBUG]: not a new instance. network config is not applied. | |
2019-08-17 20:27:02,971 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource | |
2019-08-17 20:27:02,972 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource | |
2019-08-17 20:27:02,976 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/user-data.txt - wb: [600] 570 bytes | |
2019-08-17 20:27:03,000 - util.py[DEBUG]: Attempting to load yaml from string of length 570 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,036 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/user-data.txt.i - wb: [600] 875 bytes | |
2019-08-17 20:27:03,037 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/vendor-data.txt - wb: [600] 0 bytes | |
2019-08-17 20:27:03,039 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/vendor-data.txt.i - wb: [600] 308 bytes | |
2019-08-17 20:27:03,044 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/consume_data - wb: [644] 23 bytes | |
2019-08-17 20:27:03,045 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/consume_data'>) | |
2019-08-17 20:27:03,045 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data | |
2019-08-17 20:27:03,049 - stages.py[DEBUG]: Added default handler for {'text/cloud-config', 'text/cloud-config-jsonp'} from CloudConfigPartHandler: [['text/cloud-config-jsonp', 'text/cloud-config']] | |
2019-08-17 20:27:03,050 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2019-08-17 20:27:03,050 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2019-08-17 20:27:03,050 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2019-08-17 20:27:03,051 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config-jsonp', 'text/cloud-config']] (__begin__, None, 3) with frequency once-per-instance | |
2019-08-17 20:27:03,051 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,051 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,051 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,052 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2019-08-17 20:27:03,052 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config-jsonp', 'text/cloud-config']] (text/cloud-config, part-001, 3) with frequency once-per-instance | |
2019-08-17 20:27:03,052 - util.py[DEBUG]: Attempting to load yaml from string of length 570 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,056 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2019-08-17 20:27:03,057 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config-jsonp', 'text/cloud-config']] (__end__, None, 3) with frequency once-per-instance | |
2019-08-17 20:27:03,063 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/cloud-config.txt - wb: [600] 608 bytes | |
2019-08-17 20:27:03,065 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,065 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,065 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance | |
2019-08-17 20:27:03,065 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data | |
2019-08-17 20:27:03,065 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data | |
2019-08-17 20:27:03,065 - stages.py[DEBUG]: no vendordata from datasource | |
2019-08-17 20:27:03,065 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data | |
2019-08-17 20:27:03,066 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2019-08-17 20:27:03,066 - util.py[DEBUG]: Read 2607 bytes from /etc/cloud/cloud.cfg | |
2019-08-17 20:27:03,066 - util.py[DEBUG]: Attempting to load yaml from string of length 2607 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,080 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2019-08-17 20:27:03,080 - util.py[DEBUG]: Read 172 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2019-08-17 20:27:03,080 - util.py[DEBUG]: Attempting to load yaml from string of length 172 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,081 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2019-08-17 20:27:03,082 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2019-08-17 20:27:03,082 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,086 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) | |
2019-08-17 20:27:03,086 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg | |
2019-08-17 20:27:03,086 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,088 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2019-08-17 20:27:03,088 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg | |
2019-08-17 20:27:03,088 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,090 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,090 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2019-08-17 20:27:03,090 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2019-08-17 20:27:03,091 - util.py[DEBUG]: Read 608 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2019-08-17 20:27:03,091 - util.py[DEBUG]: Attempting to load yaml from string of length 608 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,094 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2019-08-17 20:27:03,094 - util.py[DEBUG]: Read 608 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2019-08-17 20:27:03,094 - util.py[DEBUG]: Attempting to load yaml from string of length 608 with allowed root types (<class 'dict'>,) | |
2019-08-17 20:27:03,143 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource | |
2019-08-17 20:27:03,155 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 5769 bytes | |
2019-08-17 20:27:03,158 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource | |
2019-08-17 20:27:03,158 - main.py[DEBUG]: no di_report found in config. | |
2019-08-17 20:27:03,335 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:27:03,337 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_migrator.py'>) with frequency always | |
2019-08-17 20:27:03,337 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always | |
2019-08-17 20:27:03,337 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc918cc0>) | |
2019-08-17 20:27:03,338 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names | |
2019-08-17 20:27:03,339 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully | |
2019-08-17 20:27:03,339 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_seed_random.py'>) with frequency once-per-instance | |
2019-08-17 20:27:03,339 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance | |
2019-08-17 20:27:03,339 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_seed_random - wb: [644] 24 bytes | |
2019-08-17 20:27:03,340 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_seed_random'>) | |
2019-08-17 20:27:03,341 - cc_seed_random.py[DEBUG]: no command provided | |
2019-08-17 20:27:03,341 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully | |
2019-08-17 20:27:03,341 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_bootcmd.py'>) with frequency always | |
2019-08-17 20:27:03,341 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always | |
2019-08-17 20:27:03,341 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc918f60>) | |
2019-08-17 20:27:03,342 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration | |
2019-08-17 20:27:03,342 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully | |
2019-08-17 20:27:03,342 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_write_files.py'>) with frequency once-per-instance | |
2019-08-17 20:27:03,342 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance | |
2019-08-17 20:27:03,343 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_write_files - wb: [644] 24 bytes | |
2019-08-17 20:27:03,343 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_write_files'>) | |
2019-08-17 20:27:03,344 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration | |
2019-08-17 20:27:03,344 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully | |
2019-08-17 20:27:03,344 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_growpart.py'>) with frequency always | |
2019-08-17 20:27:03,344 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always | |
2019-08-17 20:27:03,344 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc9390f0>) | |
2019-08-17 20:27:03,344 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} | |
2019-08-17 20:27:03,345 - util.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:03,393 - util.py[DEBUG]: Reading from /proc/377/mountinfo (quiet=False) | |
2019-08-17 20:27:03,402 - util.py[DEBUG]: Read 2708 bytes from /proc/377/mountinfo | |
2019-08-17 20:27:03,403 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:03,455 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:03,494 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:03,535 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) | |
2019-08-17 20:27:03,545 - util.py[DEBUG]: Read 168 bytes from /proc/1/environ | |
2019-08-17 20:27:03,546 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) | |
2019-08-17 20:27:03,547 - util.py[DEBUG]: Read 1015 bytes from /proc/self/status | |
2019-08-17 20:27:03,607 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) | |
2019-08-17 20:27:03,615 - util.py[DEBUG]: Read 261 bytes from /etc/os-release | |
2019-08-17 20:27:03,617 - util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False) | |
2019-08-17 20:27:03,617 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition | |
2019-08-17 20:27:03,618 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio1/host2/target2:0:0/2:0:0:0/block/sda/dev (quiet=False) | |
2019-08-17 20:27:03,619 - util.py[DEBUG]: Read 4 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio1/host2/target2:0:0/2:0:0:0/block/sda/dev | |
2019-08-17 20:27:03,621 - util.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:03,798 - util.py[DEBUG]: Running command ['growpart', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,321 - util.py[DEBUG]: resize_devices took 0.927 seconds | |
2019-08-17 20:27:04,333 - cc_growpart.py[INFO]: '/' resized: changed (/dev/sda, 1) from 2146435072 to 53686025728 | |
2019-08-17 20:27:04,344 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully | |
2019-08-17 20:27:04,345 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_resizefs.py'>) with frequency always | |
2019-08-17 20:27:04,351 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always | |
2019-08-17 20:27:04,352 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc939828>) | |
2019-08-17 20:27:04,424 - util.py[DEBUG]: Reading from /proc/377/mountinfo (quiet=False) | |
2019-08-17 20:27:04,425 - util.py[DEBUG]: Read 2708 bytes from /proc/377/mountinfo | |
2019-08-17 20:27:04,426 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/sda1 mnt_point=/ path=/ | |
2019-08-17 20:27:04,426 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,478 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,518 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,559 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) | |
2019-08-17 20:27:04,569 - util.py[DEBUG]: Read 168 bytes from /proc/1/environ | |
2019-08-17 20:27:04,570 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) | |
2019-08-17 20:27:04,570 - util.py[DEBUG]: Read 1015 bytes from /proc/self/status | |
2019-08-17 20:27:04,573 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/sda1 | |
2019-08-17 20:27:04,574 - util.py[DEBUG]: Running command ('resize2fs', '/dev/sda1') with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,865 - util.py[DEBUG]: Resizing took 0.288 seconds | |
2019-08-17 20:27:04,875 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) | |
2019-08-17 20:27:04,887 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully | |
2019-08-17 20:27:04,888 - stages.py[DEBUG]: Running module disk_setup (<module 'cloudinit.config.cc_disk_setup' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_disk_setup.py'>) with frequency once-per-instance | |
2019-08-17 20:27:04,895 - handlers.py[DEBUG]: start: init-network/config-disk_setup: running config-disk_setup with frequency once-per-instance | |
2019-08-17 20:27:04,901 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_disk_setup - wb: [644] 24 bytes | |
2019-08-17 20:27:04,909 - helpers.py[DEBUG]: Running config-disk_setup using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_disk_setup'>) | |
2019-08-17 20:27:04,910 - handlers.py[DEBUG]: finish: init-network/config-disk_setup: SUCCESS: config-disk_setup ran successfully | |
2019-08-17 20:27:04,910 - stages.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance | |
2019-08-17 20:27:04,910 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance | |
2019-08-17 20:27:04,911 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_mounts - wb: [644] 24 bytes | |
2019-08-17 20:27:04,911 - helpers.py[DEBUG]: Running config-mounts using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_mounts'>) | |
2019-08-17 20:27:04,913 - cc_mounts.py[DEBUG]: mounts configuration is [] | |
2019-08-17 20:27:04,921 - util.py[DEBUG]: Reading from /etc/fstab (quiet=False) | |
2019-08-17 20:27:04,921 - util.py[DEBUG]: Read 118 bytes from /etc/fstab | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: changed default device swap => None | |
2019-08-17 20:27:04,923 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap | |
2019-08-17 20:27:04,924 - cc_mounts.py[DEBUG]: no need to setup swap | |
2019-08-17 20:27:04,924 - cc_mounts.py[DEBUG]: No modifications to fstab needed | |
2019-08-17 20:27:04,924 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully | |
2019-08-17 20:27:04,924 - stages.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance | |
2019-08-17 20:27:04,925 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance | |
2019-08-17 20:27:04,925 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_set_hostname - wb: [644] 24 bytes | |
2019-08-17 20:27:04,926 - helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_set_hostname'>) | |
2019-08-17 20:27:04,927 - cc_set_hostname.py[DEBUG]: Setting the hostname to Eris.sol.milkyway (Eris) | |
2019-08-17 20:27:04,928 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) | |
2019-08-17 20:27:04,928 - util.py[DEBUG]: Read 19 bytes from /etc/hostname | |
2019-08-17 20:27:04,930 - util.py[DEBUG]: Writing to /etc/hostname - wb: [644] 5 bytes | |
2019-08-17 20:27:04,932 - __init__.py[DEBUG]: Non-persistently setting the system hostname to Eris | |
2019-08-17 20:27:04,932 - util.py[DEBUG]: Running command ['hostname', 'Eris'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:04,989 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully | |
2019-08-17 20:27:04,997 - stages.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always | |
2019-08-17 20:27:05,004 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always | |
2019-08-17 20:27:05,005 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc426898>) | |
2019-08-17 20:27:05,007 - cc_update_hostname.py[DEBUG]: Updating hostname to Eris.sol.milkyway (Eris) | |
2019-08-17 20:27:05,010 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) | |
2019-08-17 20:27:05,011 - util.py[DEBUG]: Read 5 bytes from /etc/hostname | |
2019-08-17 20:27:05,013 - __init__.py[DEBUG]: Attempting to update hostname to Eris in 1 files | |
2019-08-17 20:27:05,013 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) | |
2019-08-17 20:27:05,014 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 5 bytes | |
2019-08-17 20:27:05,021 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully | |
2019-08-17 20:27:05,021 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_etc_hosts.py'>) with frequency always | |
2019-08-17 20:27:05,022 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always | |
2019-08-17 20:27:05,022 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7f2ccc426c88>) | |
2019-08-17 20:27:05,025 - util.py[DEBUG]: Reading from /etc/cloud/templates/hosts.debian.tmpl (quiet=False) | |
2019-08-17 20:27:05,025 - util.py[DEBUG]: Read 901 bytes from /etc/cloud/templates/hosts.debian.tmpl | |
2019-08-17 20:27:05,026 - templater.py[DEBUG]: Rendering content of '/etc/cloud/templates/hosts.debian.tmpl' using renderer jinja | |
2019-08-17 20:27:05,073 - util.py[DEBUG]: Writing to /etc/hosts - wb: [644] 604 bytes | |
2019-08-17 20:27:05,074 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully | |
2019-08-17 20:27:05,074 - stages.py[DEBUG]: Running module ca-certs (<module 'cloudinit.config.cc_ca_certs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ca_certs.py'>) with frequency once-per-instance | |
2019-08-17 20:27:05,074 - handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance | |
2019-08-17 20:27:05,076 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ca_certs - wb: [644] 24 bytes | |
2019-08-17 20:27:05,077 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ca_certs'>) | |
2019-08-17 20:27:05,077 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration | |
2019-08-17 20:27:05,078 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully | |
2019-08-17 20:27:05,078 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_rsyslog.py'>) with frequency once-per-instance | |
2019-08-17 20:27:05,079 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance | |
2019-08-17 20:27:05,079 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_rsyslog - wb: [644] 24 bytes | |
2019-08-17 20:27:05,079 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_rsyslog'>) | |
2019-08-17 20:27:05,080 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration | |
2019-08-17 20:27:05,080 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully | |
2019-08-17 20:27:05,080 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance | |
2019-08-17 20:27:05,080 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance | |
2019-08-17 20:27:05,081 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_users_groups - wb: [644] 24 bytes | |
2019-08-17 20:27:05,081 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_users_groups'>) | |
2019-08-17 20:27:05,085 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2019-08-17 20:27:05,086 - util.py[DEBUG]: Read 261 bytes from /etc/os-release | |
2019-08-17 20:27:05,089 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2019-08-17 20:27:05,089 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2019-08-17 20:27:05,091 - __init__.py[DEBUG]: Adding user debian | |
2019-08-17 20:27:05,091 - util.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'debian', '--comment', 'Debian', '--groups', 'adm,audio,cdrom,dialout,dip,floppy,netdev,plugdev,sudo,video', '--shell', '/bin/bash', '-m'] | |
2019-08-17 20:27:05,496 - util.py[DEBUG]: Running command ['passwd', '-l', 'debian'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:05,620 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) | |
2019-08-17 20:27:05,629 - util.py[DEBUG]: Read 669 bytes from /etc/sudoers | |
2019-08-17 20:27:05,652 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 122 bytes | |
2019-08-17 20:27:05,663 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully | |
2019-08-17 20:27:05,665 - stages.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance | |
2019-08-17 20:27:05,670 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance | |
2019-08-17 20:27:05,673 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh - wb: [644] 23 bytes | |
2019-08-17 20:27:05,674 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh'>) | |
2019-08-17 20:27:05,683 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'rsa', '-N', '', '-f', '/etc/ssh/ssh_host_rsa_key'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:05,839 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'dsa', '-N', '', '-f', '/etc/ssh/ssh_host_dsa_key'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:06,308 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ecdsa', '-N', '', '-f', '/etc/ssh/ssh_host_ecdsa_key'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:06,375 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ed25519', '-N', '', '-f', '/etc/ssh/ssh_host_ed25519_key'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:06,457 - util.py[DEBUG]: Changing the ownership of /home/debian/.ssh to 1000:1000 | |
2019-08-17 20:27:06,467 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2019-08-17 20:27:06,468 - util.py[DEBUG]: Read 3250 bytes from /etc/ssh/sshd_config | |
2019-08-17 20:27:06,471 - util.py[DEBUG]: Writing to /home/debian/.ssh/authorized_keys - wb: [600] 402 bytes | |
2019-08-17 20:27:06,472 - util.py[DEBUG]: Changing the ownership of /home/debian/.ssh/authorized_keys to 1000:1000 | |
2019-08-17 20:27:06,473 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 | |
2019-08-17 20:27:06,473 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2019-08-17 20:27:06,473 - util.py[DEBUG]: Read 3250 bytes from /etc/ssh/sshd_config | |
2019-08-17 20:27:06,474 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 557 bytes | |
2019-08-17 20:27:06,475 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 | |
2019-08-17 20:27:06,484 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully | |
2019-08-17 20:27:06,491 - main.py[DEBUG]: Ran 15 modules with 0 failures | |
2019-08-17 20:27:06,506 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2019-08-17 20:27:06,506 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2019-08-17 20:27:06,507 - util.py[DEBUG]: cloud-init mode 'init' took 4.223 seconds (4.23) | |
2019-08-17 20:27:06,508 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources | |
2019-08-17 20:27:08,248 - util.py[DEBUG]: Cloud-init v. 18.3 running 'modules:config' at Sat, 17 Aug 2019 20:27:07 +0000. Up 14.25 seconds. | |
2019-08-17 20:27:08,432 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:27:08,435 - stages.py[DEBUG]: Running module emit_upstart (<module 'cloudinit.config.cc_emit_upstart' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_emit_upstart.py'>) with frequency always | |
2019-08-17 20:27:08,437 - handlers.py[DEBUG]: start: modules-config/config-emit_upstart: running config-emit_upstart with frequency always | |
2019-08-17 20:27:08,438 - helpers.py[DEBUG]: Running config-emit_upstart using lock (<cloudinit.helpers.DummyLock object at 0x7f3a8b8cd048>) | |
2019-08-17 20:27:08,439 - cc_emit_upstart.py[DEBUG]: no /sbin/initctl located | |
2019-08-17 20:27:08,439 - cc_emit_upstart.py[DEBUG]: not upstart system, 'emit_upstart' disabled | |
2019-08-17 20:27:08,439 - handlers.py[DEBUG]: finish: modules-config/config-emit_upstart: SUCCESS: config-emit_upstart ran successfully | |
2019-08-17 20:27:08,439 - stages.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) with frequency once-per-instance | |
2019-08-17 20:27:08,439 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance | |
2019-08-17 20:27:08,441 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh_import_id - wb: [644] 23 bytes | |
2019-08-17 20:27:08,442 - helpers.py[DEBUG]: Running config-ssh-import-id using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh_import_id'>) | |
2019-08-17 20:27:08,444 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id ran successfully | |
2019-08-17 20:27:08,444 - stages.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance | |
2019-08-17 20:27:08,445 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance | |
2019-08-17 20:27:08,445 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_locale - wb: [644] 24 bytes | |
2019-08-17 20:27:08,446 - helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_locale'>) | |
2019-08-17 20:27:08,446 - util.py[DEBUG]: Reading from /etc/default/locale (quiet=False) | |
2019-08-17 20:27:08,446 - util.py[DEBUG]: Read 35 bytes from /etc/default/locale | |
2019-08-17 20:27:08,448 - cc_locale.py[DEBUG]: Setting locale to en_US.UTF-8 | |
2019-08-17 20:27:08,448 - util.py[DEBUG]: Reading from /etc/default/locale (quiet=False) | |
2019-08-17 20:27:08,448 - util.py[DEBUG]: Read 35 bytes from /etc/default/locale | |
2019-08-17 20:27:08,448 - debian.py[DEBUG]: Generating locales for en_US.UTF-8 | |
2019-08-17 20:27:08,448 - util.py[DEBUG]: Running command ['locale-gen', 'en_US.UTF-8'] with allowed return codes [0] (shell=False, capture=False) | |
2019-08-17 20:27:08,546 - debian.py[DEBUG]: Updating /etc/default/locale with locale setting LANG=en_US.UTF-8 | |
2019-08-17 20:27:08,557 - util.py[DEBUG]: Running command ['update-locale', '--locale-file=/etc/default/locale', 'LANG=en_US.UTF-8'] with allowed return codes [0] (shell=False, capture=False) | |
2019-08-17 20:27:08,710 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully | |
2019-08-17 20:27:08,718 - stages.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance | |
2019-08-17 20:27:08,725 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance | |
2019-08-17 20:27:08,731 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_set_passwords - wb: [644] 23 bytes | |
2019-08-17 20:27:08,745 - helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_set_passwords'>) | |
2019-08-17 20:27:08,747 - cc_set_passwords.py[DEBUG]: Leaving ssh config 'PasswordAuthentication' unchanged. ssh_pwauth=None | |
2019-08-17 20:27:08,748 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully | |
2019-08-17 20:27:08,748 - stages.py[DEBUG]: Running module grub-dpkg (<module 'cloudinit.config.cc_grub_dpkg' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_grub_dpkg.py'>) with frequency once-per-instance | |
2019-08-17 20:27:08,748 - handlers.py[DEBUG]: start: modules-config/config-grub-dpkg: running config-grub-dpkg with frequency once-per-instance | |
2019-08-17 20:27:08,748 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_grub_dpkg - wb: [644] 24 bytes | |
2019-08-17 20:27:08,749 - helpers.py[DEBUG]: Running config-grub-dpkg using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_grub_dpkg'>) | |
2019-08-17 20:27:08,749 - cc_grub_dpkg.py[DEBUG]: Setting grub debconf-set-selections with '/dev/sda','false' | |
2019-08-17 20:27:08,749 - util.py[DEBUG]: Running command ['debconf-set-selections'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:09,244 - handlers.py[DEBUG]: finish: modules-config/config-grub-dpkg: SUCCESS: config-grub-dpkg ran successfully | |
2019-08-17 20:27:09,253 - stages.py[DEBUG]: Running module apt-pipelining (<module 'cloudinit.config.cc_apt_pipelining' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_pipelining.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,261 - handlers.py[DEBUG]: start: modules-config/config-apt-pipelining: running config-apt-pipelining with frequency once-per-instance | |
2019-08-17 20:27:09,268 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_apt_pipelining - wb: [644] 24 bytes | |
2019-08-17 20:27:09,279 - helpers.py[DEBUG]: Running config-apt-pipelining using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_apt_pipelining'>) | |
2019-08-17 20:27:09,281 - util.py[DEBUG]: Writing to /etc/apt/apt.conf.d/90cloud-init-pipelining - wb: [644] 80 bytes | |
2019-08-17 20:27:09,282 - cc_apt_pipelining.py[DEBUG]: Wrote /etc/apt/apt.conf.d/90cloud-init-pipelining with apt pipeline depth setting 0 | |
2019-08-17 20:27:09,282 - handlers.py[DEBUG]: finish: modules-config/config-apt-pipelining: SUCCESS: config-apt-pipelining ran successfully | |
2019-08-17 20:27:09,283 - stages.py[DEBUG]: Running module apt-configure (<module 'cloudinit.config.cc_apt_configure' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_configure.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,283 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance | |
2019-08-17 20:27:09,283 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_apt_configure - wb: [644] 24 bytes | |
2019-08-17 20:27:09,284 - helpers.py[DEBUG]: Running config-apt-configure using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_apt_configure'>) | |
2019-08-17 20:27:09,285 - cc_apt_configure.py[DEBUG]: debconf_selections was not set in config | |
2019-08-17 20:27:09,286 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2019-08-17 20:27:09,286 - util.py[DEBUG]: Read 261 bytes from /etc/os-release | |
2019-08-17 20:27:09,291 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2019-08-17 20:27:09,292 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2019-08-17 20:27:09,294 - cc_apt_configure.py[DEBUG]: handling apt config: {} | |
2019-08-17 20:27:09,297 - util.py[DEBUG]: Running command ['lsb_release', '--all'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:09,457 - util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:09,507 - cc_apt_configure.py[DEBUG]: got primary mirror: None | |
2019-08-17 20:27:09,524 - cc_apt_configure.py[DEBUG]: got security mirror: None | |
2019-08-17 20:27:09,527 - util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:09,594 - __init__.py[DEBUG]: filtered distro mirror info: {'primary': 'http://deb.debian.org/debian', 'security': 'http://security.debian.org/'} | |
2019-08-17 20:27:09,605 - cc_apt_configure.py[DEBUG]: Apt Mirror info: {'primary': 'http://deb.debian.org/debian', 'security': 'http://security.debian.org/', 'PRIMARY': 'http://deb.debian.org/debian', 'SECURITY': 'http://security.debian.org/', 'MIRROR': 'http://deb.debian.org/debian'} | |
2019-08-17 20:27:09,607 - cc_apt_configure.py[INFO]: No custom template provided, fall back to builtin | |
2019-08-17 20:27:09,609 - util.py[DEBUG]: Reading from /etc/cloud/templates/sources.list.debian.tmpl (quiet=False) | |
2019-08-17 20:27:09,611 - util.py[DEBUG]: Read 1295 bytes from /etc/cloud/templates/sources.list.debian.tmpl | |
2019-08-17 20:27:09,664 - util.py[DEBUG]: Writing to /etc/apt/sources.list - wb: [644] 1367 bytes | |
2019-08-17 20:27:09,672 - util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:09,759 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure ran successfully | |
2019-08-17 20:27:09,765 - stages.py[DEBUG]: Running module ntp (<module 'cloudinit.config.cc_ntp' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ntp.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,770 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance | |
2019-08-17 20:27:09,775 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ntp - wb: [644] 22 bytes | |
2019-08-17 20:27:09,783 - helpers.py[DEBUG]: Running config-ntp using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ntp'>) | |
2019-08-17 20:27:09,784 - cc_ntp.py[DEBUG]: Skipping module named ntp, not present or disabled by cfg | |
2019-08-17 20:27:09,785 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully | |
2019-08-17 20:27:09,785 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_timezone.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,786 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance | |
2019-08-17 20:27:09,786 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_timezone - wb: [644] 24 bytes | |
2019-08-17 20:27:09,787 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_timezone'>) | |
2019-08-17 20:27:09,787 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified | |
2019-08-17 20:27:09,787 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully | |
2019-08-17 20:27:09,787 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_disable_ec2_metadata.py'>) with frequency always | |
2019-08-17 20:27:09,788 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always | |
2019-08-17 20:27:09,788 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x7f3a8b3a24e0>) | |
2019-08-17 20:27:09,788 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled | |
2019-08-17 20:27:09,788 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully | |
2019-08-17 20:27:09,788 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,789 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance | |
2019-08-17 20:27:09,789 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_runcmd - wb: [644] 23 bytes | |
2019-08-17 20:27:09,790 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_runcmd'>) | |
2019-08-17 20:27:09,790 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration | |
2019-08-17 20:27:09,790 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully | |
2019-08-17 20:27:09,790 - stages.py[DEBUG]: Running module byobu (<module 'cloudinit.config.cc_byobu' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_byobu.py'>) with frequency once-per-instance | |
2019-08-17 20:27:09,790 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance | |
2019-08-17 20:27:09,790 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_byobu - wb: [644] 24 bytes | |
2019-08-17 20:27:09,791 - helpers.py[DEBUG]: Running config-byobu using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_byobu'>) | |
2019-08-17 20:27:09,791 - cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found | |
2019-08-17 20:27:09,791 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu ran successfully | |
2019-08-17 20:27:09,794 - main.py[DEBUG]: Ran 12 modules with 0 failures | |
2019-08-17 20:27:09,807 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2019-08-17 20:27:09,808 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2019-08-17 20:27:09,808 - util.py[DEBUG]: cloud-init mode 'modules' took 1.904 seconds (1.90) | |
2019-08-17 20:27:09,809 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config | |
2019-08-17 20:27:11,210 - util.py[DEBUG]: Cloud-init v. 18.3 running 'modules:final' at Sat, 17 Aug 2019 20:27:10 +0000. Up 17.22 seconds. | |
2019-08-17 20:27:11,378 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> | |
2019-08-17 20:27:11,380 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance | |
2019-08-17 20:27:11,382 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance | |
2019-08-17 20:27:11,384 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_package_update_upgrade_install - wb: [644] 24 bytes | |
2019-08-17 20:27:11,385 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_package_update_upgrade_install'>) | |
2019-08-17 20:27:11,385 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/update_sources - wb: [644] 24 bytes | |
2019-08-17 20:27:11,386 - helpers.py[DEBUG]: Running update-sources using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/update_sources'>) | |
2019-08-17 20:27:11,387 - util.py[DEBUG]: Running command ['apt-get', '--option=Dpkg::Options::=--force-confold', '--option=Dpkg::options::=--force-unsafe-io', '--assume-yes', '--quiet', 'update'] with allowed return codes [0] (shell=False, capture=False) | |
2019-08-17 20:27:16,118 - util.py[DEBUG]: apt-update [apt-get --option=Dpkg::Options::=--force-confold --option=Dpkg::options::=--force-unsafe-io --assume-yes --quiet update] took 4.728 seconds | |
2019-08-17 20:27:16,132 - util.py[DEBUG]: Running command ['apt-get', '--option=Dpkg::Options::=--force-confold', '--option=Dpkg::options::=--force-unsafe-io', '--assume-yes', '--quiet', 'dist-upgrade'] with allowed return codes [0] (shell=False, capture=False) | |
2019-08-17 20:27:16,929 - util.py[DEBUG]: apt-upgrade [apt-get --option=Dpkg::Options::=--force-confold --option=Dpkg::options::=--force-unsafe-io --assume-yes --quiet dist-upgrade] took 0.793 seconds | |
2019-08-17 20:27:16,945 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully | |
2019-08-17 20:27:16,946 - stages.py[DEBUG]: Running module fan (<module 'cloudinit.config.cc_fan' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_fan.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,950 - handlers.py[DEBUG]: start: modules-final/config-fan: running config-fan with frequency once-per-instance | |
2019-08-17 20:27:16,954 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_fan - wb: [644] 23 bytes | |
2019-08-17 20:27:16,961 - helpers.py[DEBUG]: Running config-fan using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_fan'>) | |
2019-08-17 20:27:16,965 - cc_fan.py[DEBUG]: fan: no 'fan' config entry. disabling | |
2019-08-17 20:27:16,971 - handlers.py[DEBUG]: finish: modules-final/config-fan: SUCCESS: config-fan ran successfully | |
2019-08-17 20:27:16,971 - stages.py[DEBUG]: Running module puppet (<module 'cloudinit.config.cc_puppet' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_puppet.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,972 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance | |
2019-08-17 20:27:16,972 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_puppet - wb: [644] 24 bytes | |
2019-08-17 20:27:16,973 - helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_puppet'>) | |
2019-08-17 20:27:16,973 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found | |
2019-08-17 20:27:16,973 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet ran successfully | |
2019-08-17 20:27:16,973 - stages.py[DEBUG]: Running module chef (<module 'cloudinit.config.cc_chef' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_chef.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,973 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency once-per-instance | |
2019-08-17 20:27:16,973 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_chef - wb: [644] 24 bytes | |
2019-08-17 20:27:16,974 - helpers.py[DEBUG]: Running config-chef using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_chef'>) | |
2019-08-17 20:27:16,974 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration | |
2019-08-17 20:27:16,974 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully | |
2019-08-17 20:27:16,974 - stages.py[DEBUG]: Running module salt-minion (<module 'cloudinit.config.cc_salt_minion' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_salt_minion.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,975 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance | |
2019-08-17 20:27:16,975 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_salt_minion - wb: [644] 23 bytes | |
2019-08-17 20:27:16,975 - helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_salt_minion'>) | |
2019-08-17 20:27:16,976 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration | |
2019-08-17 20:27:16,976 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion ran successfully | |
2019-08-17 20:27:16,976 - stages.py[DEBUG]: Running module mcollective (<module 'cloudinit.config.cc_mcollective' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mcollective.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,976 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance | |
2019-08-17 20:27:16,976 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_mcollective - wb: [644] 24 bytes | |
2019-08-17 20:27:16,977 - helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_mcollective'>) | |
2019-08-17 20:27:16,977 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration | |
2019-08-17 20:27:16,977 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective ran successfully | |
2019-08-17 20:27:16,977 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_rightscale_userdata.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,978 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance | |
2019-08-17 20:27:16,978 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_rightscale_userdata - wb: [644] 24 bytes | |
2019-08-17 20:27:16,979 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_rightscale_userdata'>) | |
2019-08-17 20:27:16,979 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata | |
2019-08-17 20:27:16,979 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully | |
2019-08-17 20:27:16,979 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,979 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance | |
2019-08-17 20:27:16,980 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_vendor - wb: [644] 24 bytes | |
2019-08-17 20:27:16,980 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_vendor'>) | |
2019-08-17 20:27:16,981 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully | |
2019-08-17 20:27:16,981 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once | |
2019-08-17 20:27:16,981 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once | |
2019-08-17 20:27:16,982 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 23 bytes | |
2019-08-17 20:27:16,982 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>) | |
2019-08-17 20:27:16,983 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully | |
2019-08-17 20:27:16,983 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always | |
2019-08-17 20:27:16,983 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always | |
2019-08-17 20:27:16,984 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7f4c0ff69160>) | |
2019-08-17 20:27:16,984 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully | |
2019-08-17 20:27:16,984 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,984 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance | |
2019-08-17 20:27:16,985 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_per_instance - wb: [644] 24 bytes | |
2019-08-17 20:27:16,985 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_per_instance'>) | |
2019-08-17 20:27:16,985 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully | |
2019-08-17 20:27:16,986 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,986 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance | |
2019-08-17 20:27:16,986 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_user - wb: [644] 24 bytes | |
2019-08-17 20:27:16,987 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_scripts_user'>) | |
2019-08-17 20:27:16,987 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully | |
2019-08-17 20:27:16,987 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance | |
2019-08-17 20:27:16,987 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance | |
2019-08-17 20:27:16,988 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh_authkey_fingerprints - wb: [644] 23 bytes | |
2019-08-17 20:27:16,988 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_ssh_authkey_fingerprints'>) | |
2019-08-17 20:27:16,997 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2019-08-17 20:27:16,998 - util.py[DEBUG]: Read 3250 bytes from /etc/ssh/sshd_config | |
2019-08-17 20:27:17,002 - util.py[DEBUG]: Reading from /home/debian/.ssh/authorized_keys (quiet=False) | |
2019-08-17 20:27:17,002 - util.py[DEBUG]: Read 402 bytes from /home/debian/.ssh/authorized_keys | |
2019-08-17 20:27:17,017 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints ran successfully | |
2019-08-17 20:27:17,017 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance | |
2019-08-17 20:27:17,017 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance | |
2019-08-17 20:27:17,017 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_keys_to_console - wb: [644] 24 bytes | |
2019-08-17 20:27:17,018 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_keys_to_console'>) | |
2019-08-17 20:27:17,018 - util.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True) | |
2019-08-17 20:27:17,145 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully | |
2019-08-17 20:27:17,150 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_phone_home.py'>) with frequency once-per-instance | |
2019-08-17 20:27:17,155 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance | |
2019-08-17 20:27:17,160 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_phone_home - wb: [644] 24 bytes | |
2019-08-17 20:27:17,166 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_phone_home'>) | |
2019-08-17 20:27:17,167 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found | |
2019-08-17 20:27:17,168 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully | |
2019-08-17 20:27:17,168 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_final_message.py'>) with frequency always | |
2019-08-17 20:27:17,168 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always | |
2019-08-17 20:27:17,168 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7f4c0faa3240>) | |
2019-08-17 20:27:17,169 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2019-08-17 20:27:17,169 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2019-08-17 20:27:17,210 - util.py[DEBUG]: Cloud-init v. 18.3 finished at Sat, 17 Aug 2019 20:27:17 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net]. Up 23.32 seconds | |
2019-08-17 20:27:17,210 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 50 bytes | |
2019-08-17 20:27:17,211 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully | |
2019-08-17 20:27:17,211 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_power_state_change.py'>) with frequency once-per-instance | |
2019-08-17 20:27:17,212 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance | |
2019-08-17 20:27:17,212 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_power_state_change - wb: [644] 24 bytes | |
2019-08-17 20:27:17,213 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/292d95f4c60b81cc9ce0125a208d51894c93e2ce/sem/config_power_state_change'>) | |
2019-08-17 20:27:17,213 - cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing | |
2019-08-17 20:27:17,213 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully | |
2019-08-17 20:27:17,216 - main.py[DEBUG]: Ran 17 modules with 0 failures | |
2019-08-17 20:27:17,224 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' | |
2019-08-17 20:27:17,224 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2019-08-17 20:27:17,224 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2019-08-17 20:27:17,225 - util.py[DEBUG]: cloud-init mode 'modules' took 6.352 seconds (6.17) | |
2019-08-17 20:27:17,225 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment