Package: cloud-init Version: 20.2-2 Severity: normal I started a cloud-image supplying the attached user-data, meta-data (empty) and network-config through a NoCloud ISO image. After booting the image, cloud-init created the expected configuration in /etc/udev/rules/70-persistent-net.rules and /etc/network/interfaces.d/50-cloud-init. However, the network interface doesn't actually get renamed and configured. Instead a configuration with dhcp enabled is created in /run/network/interfaces.d/ and is used to configure the interface. When the machine is booted a second time, the udev rule renames the interface as appropriate and the interface is configured with the supplied static network configuration.
This seems to happen, because udev doesn't evaluate the udev rule after it is created, because rules for that interface were already processed earlier after boot. cloud-init apparently doesn't trigger renaming the interface explicitly after creating the udev rule, unless the `set-name` property is set for that interface. This problem can be worked aroung, by setting the `set-name` property, which causes cloud-init to rename the interface itself. However I would expect that cloud-init handles the missing `set-name` either by recognizing that the eni renderer always requires the interface to be renamed and act as if `set-name` was given or that it at least fails with a coherent failure message, stating that `set-name` needs to be set when the eni renderer is used. The current behavior that ends up in a silent semi-failure state is really confusing. I used the following official cloud image: debian-11-genericcloud-amd64-daily-20200716-329.qcow2 The cloud-init logs are attached Regards Sven -- System Information: Debian Release: bullseye/sid APT prefers testing APT policy: (500, 'testing') Architecture: amd64 (x86_64) Kernel: Linux 5.7.0-1-cloud-amd64 (SMP w/2 CPU threads) Locale: LANG=C.UTF-8, LC_CTYPE=C.UTF-8 (charmap=UTF-8), LANGUAGE not set Shell: /bin/sh linked to /usr/bin/dash Init: systemd (via /run/systemd/system) LSM: AppArmor: enabled Versions of packages cloud-init depends on: ii fdisk 2.35.2-6 ii gdisk 1.0.5-1 ii ifupdown 0.8.35+b1 ii locales 2.30-8 ii lsb-base 11.1.0 ii lsb-release 11.1.0 ii net-tools 1.60+git20180626.aebd88e-1 ii procps 2:3.3.16-5 ii python3 3.8.2-3 ii python3-configobj 5.0.6-4 ii python3-jinja2 2.11.2-1 ii python3-jsonpatch 1.25-3 ii python3-jsonschema 3.2.0-3 ii python3-oauthlib 3.1.0-2 ii python3-requests 2.23.0+dfsg-2 ii python3-yaml 5.3.1-2 ii util-linux 2.35.2-6 Versions of packages cloud-init recommends: ii cloud-guest-utils 0.31-2 pn eatmydata <none> ii sudo 1.9.1-1 Versions of packages cloud-init suggests: pn btrfs-progs <none> ii e2fsprogs 1.45.6-1 pn xfsprogs <none> -- no debconf information
2020-07-16 13:02:42,838 - util.py[DEBUG]: Cloud-init v. 20.2 running 'init-local' at Thu, 16 Jul 2020 13:02:42 +0000. Up 1.99 seconds. 2020-07-16 13:02:42,838 - main.py[DEBUG]: No kernel command line url found. 2020-07-16 13:02:42,838 - main.py[DEBUG]: Closing stdin. 2020-07-16 13:02:42,840 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes 2020-07-16 13:02:42,841 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4 2020-07-16 13:02:42,841 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished 2020-07-16 13:02:42,841 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net 2020-07-16 13:02:42,841 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] 2020-07-16 13:02:42,841 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2020-07-16 13:02:42,841 - stages.py[DEBUG]: no cache found 2020-07-16 13:02:42,841 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found 2020-07-16 13:02:42,841 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2020-07-16 13:02:42,843 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:42,843 - __init__.py[DEBUG]: Looking for data source in: ['NoCloud', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] 2020-07-16 13:02:42,845 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceNoCloud'] 2020-07-16 13:02:42,846 - handlers.py[DEBUG]: start: init-local/search-NoCloud: searching for local data from DataSourceNoCloud 2020-07-16 13:02:42,846 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceNoCloud.DataSourceNoCloud'> 2020-07-16 13:02:42,846 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: New instance first boot 2020-07-16 13:02:42,846 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:42,851 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:42,852 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Read 145 bytes from /proc/1/environ 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Read 1037 bytes from /proc/self/status 2020-07-16 13:02:42,853 - util.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_serial 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /sys/class/dmi/id/product_serial (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Read 1 bytes from /sys/class/dmi/id/product_serial 2020-07-16 13:02:42,853 - util.py[DEBUG]: dmi data /sys/class/dmi/id/product_serial returned 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) 2020-07-16 13:02:42,853 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/user-data (quiet=False) 2020-07-16 13:02:42,854 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) 2020-07-16 13:02:42,854 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/vendor-data (quiet=False) 2020-07-16 13:02:42,854 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/network-config (quiet=False) 2020-07-16 13:02:42,865 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) 2020-07-16 13:02:42,865 - util.py[DEBUG]: Read 200 bytes from /etc/os-release 2020-07-16 13:02:42,866 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2020-07-16 13:02:42,877 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2020-07-16 13:02:42,882 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=CIDATA', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2020-07-16 13:02:42,887 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2020-07-16 13:02:42,892 - DataSourceNoCloud.py[DEBUG]: Attempting to use data from /dev/sr0 2020-07-16 13:02:42,892 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False) 2020-07-16 13:02:42,892 - util.py[DEBUG]: Read 2395 bytes from /proc/mounts 2020-07-16 13:02:42,892 - 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,noexec,relatime,size=494016k,nr_inodes=123504,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,discard,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/perf_event', 'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event'}, 'pstore': {'fstype': 'pstore', 'mountpoint': '/sys/fs/pstore', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'efivarfs': {'fstype': 'efivarfs', 'mountpoint': '/sys/firmware/efi/efivars', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'none': {'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=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=10339'}, 'hugetlbfs': {'fstype': 'hugetlbfs', 'mountpoint': '/dev/hugepages', 'opts': 'rw,relatime,pagesize=2M'}, 'mqueue': {'fstype': 'mqueue', 'mountpoint': '/dev/mqueue', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'debugfs': {'fstype': 'debugfs', 'mountpoint': '/sys/kernel/debug', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'tracefs': {'fstype': 'tracefs', 'mountpoint': '/sys/kernel/tracing', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, '/dev/sda15': {'fstype': 'vfat', 'mountpoint': '/boot/efi', 'opts': 'rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro'}} mounts from proc 2020-07-16 13:02:42,892 - util.py[DEBUG]: Running command ['mount', '-o', 'ro', '-t', 'auto', '/dev/sr0', '/run/cloud-init/tmp/tmpj6go0b74'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:42,903 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmpj6go0b74//user-data (quiet=False) 2020-07-16 13:02:42,903 - util.py[DEBUG]: Read 656 bytes from /run/cloud-init/tmp/tmpj6go0b74//user-data 2020-07-16 13:02:42,903 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmpj6go0b74//meta-data (quiet=False) 2020-07-16 13:02:42,903 - util.py[DEBUG]: Read 0 bytes from /run/cloud-init/tmp/tmpj6go0b74//meta-data 2020-07-16 13:02:42,903 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmpj6go0b74//vendor-data (quiet=False) 2020-07-16 13:02:42,903 - util.py[DEBUG]: Reading from /run/cloud-init/tmp/tmpj6go0b74//network-config (quiet=False) 2020-07-16 13:02:42,903 - util.py[DEBUG]: Read 144 bytes from /run/cloud-init/tmp/tmpj6go0b74//network-config 2020-07-16 13:02:42,903 - util.py[DEBUG]: Running command ['umount', '/run/cloud-init/tmp/tmpj6go0b74'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:42,907 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,908 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:42,908 - util.py[DEBUG]: Attempting to load yaml from string of length 144 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,909 - DataSourceNoCloud.py[DEBUG]: Using data from /dev/sr0 2020-07-16 13:02:42,909 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) 2020-07-16 13:02:42,909 - util.py[DEBUG]: Read 109 bytes from /etc/hosts 2020-07-16 13:02:42,910 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmp12cukssj) - w: [600] 4435 bytes/chars 2020-07-16 13:02:42,910 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmp8edog07t) - w: [644] 1343 bytes/chars 2020-07-16 13:02:42,911 - handlers.py[DEBUG]: finish: init-local/search-NoCloud: SUCCESS: found local data from DataSourceNoCloud 2020-07-16 13:02:42,911 - stages.py[INFO]: Loaded datasource DataSourceNoCloud - DataSourceNoCloud [seed=/dev/sr0][dsmode=net] 2020-07-16 13:02:42,911 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2020-07-16 13:02:42,911 - util.py[DEBUG]: Read 2637 bytes from /etc/cloud/cloud.cfg 2020-07-16 13:02:42,911 - util.py[DEBUG]: Attempting to load yaml from string of length 2637 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,917 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2020-07-16 13:02:42,917 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2020-07-16 13:02:42,917 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,919 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) 2020-07-16 13:02:42,919 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg 2020-07-16 13:02:42,919 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,920 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2020-07-16 13:02:42,920 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg 2020-07-16 13:02:42,920 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,920 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,920 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:42,920 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2020-07-16 13:02:42,921 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/nocloud' 2020-07-16 13:02:42,921 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/nocloud/datasource (quiet=False) 2020-07-16 13:02:42,921 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/datasource - wb: [644] 65 bytes 2020-07-16 13:02:42,922 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 65 bytes 2020-07-16 13:02:42,922 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2020-07-16 13:02:42,922 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID 2020-07-16 13:02:42,922 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 8 bytes 2020-07-16 13:02:42,922 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 8 bytes 2020-07-16 13:02:42,922 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes 2020-07-16 13:02:42,923 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 4721 bytes 2020-07-16 13:02:42,923 - main.py[DEBUG]: [local] init will now be targeting instance id: nocloud. new=True 2020-07-16 13:02:42,923 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2020-07-16 13:02:42,923 - util.py[DEBUG]: Read 2637 bytes from /etc/cloud/cloud.cfg 2020-07-16 13:02:42,923 - util.py[DEBUG]: Attempting to load yaml from string of length 2637 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,929 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2020-07-16 13:02:42,929 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2020-07-16 13:02:42,929 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,931 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) 2020-07-16 13:02:42,931 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg 2020-07-16 13:02:42,931 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,932 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2020-07-16 13:02:42,932 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg 2020-07-16 13:02:42,932 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,932 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:42,932 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:42,933 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:42,933 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/address (quiet=False) 2020-07-16 13:02:42,933 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enp1s0/address 2020-07-16 13:02:42,933 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2020-07-16 13:02:42,933 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/addr_assign_type (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enp1s0/addr_assign_type 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/uevent (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 27 bytes from /sys/class/net/enp1s0/uevent 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/address (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enp1s0/address 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/device/device (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/enp1s0/device/device 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/type (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enp1s0/type 2020-07-16 13:02:42,934 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2020-07-16 13:02:42,934 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2020-07-16 13:02:42,934 - __init__.py[DEBUG]: net: all expected physical devices present 2020-07-16 13:02:42,934 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'main': {'match': {'macaddress': '52:54:00:8c:fc:10'}, 'addresses': ['192.168.122.100'], 'gateway4': '192.168.122.1'}}} 2020-07-16 13:02:42,934 - __init__.py[DEBUG]: no interfaces to rename 2020-07-16 13:02:42,935 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 2, 'ethernets': {'main': {'match': {'macaddress': '52:54:00:8c:fc:10'}, 'addresses': ['192.168.122.100'], 'gateway4': '192.168.122.1'}}} 2020-07-16 13:02:42,935 - __init__.py[DEBUG]: Selected renderer 'eni' from priority list: None 2020-07-16 13:02:42,935 - network_state.py[DEBUG]: v2(ethernets) -> v1(physical): {'type': 'physical', 'name': 'main', 'mac_address': '52:54:00:8c:fc:10', 'match': {'macaddress': '52:54:00:8c:fc:10'}, 'subnets': [{'type': 'static', 'address': '192.168.122.100', 'gateway': '192.168.122.1'}]} 2020-07-16 13:02:42,936 - network_state.py[DEBUG]: v2_common: handling config: {'main': {'match': {'macaddress': '52:54:00:8c:fc:10'}, 'addresses': ['192.168.122.100'], 'gateway4': '192.168.122.1'}} 2020-07-16 13:02:42,937 - util.py[DEBUG]: Writing to /etc/network/interfaces.d/50-cloud-init - wb: [644] 434 bytes 2020-07-16 13:02:42,937 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [644] 96 bytes 2020-07-16 13:02:42,937 - main.py[DEBUG]: [local] Exiting. datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net] not in local mode. 2020-07-16 13:02:42,937 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp0e5nbbz4) - w: [644] 518 bytes/chars 2020-07-16 13:02:42,937 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2020-07-16 13:02:42,937 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2020-07-16 13:02:42,937 - util.py[DEBUG]: cloud-init mode 'init' took 0.124 seconds (0.12) 2020-07-16 13:02:42,938 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources 2020-07-16 13:02:52,549 - util.py[DEBUG]: Cloud-init v. 20.2 running 'init' at Thu, 16 Jul 2020 13:02:52 +0000. Up 11.70 seconds. 2020-07-16 13:02:52,549 - main.py[DEBUG]: No kernel command line url found. 2020-07-16 13:02:52,549 - main.py[DEBUG]: Closing stdin. 2020-07-16 13:02:52,550 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes 2020-07-16 13:02:52,551 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4 2020-07-16 13:02:52,560 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) 2020-07-16 13:02:52,560 - util.py[DEBUG]: Read 200 bytes from /etc/os-release 2020-07-16 13:02:52,560 - util.py[DEBUG]: Running command ['ip', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,564 - util.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,566 - util.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) 2020-07-16 13:02:52,568 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. 2020-07-16 13:02:52,568 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. 2020-07-16 13:02:52,568 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] 2020-07-16 13:02:52,568 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2020-07-16 13:02:52,569 - util.py[DEBUG]: Read 4721 bytes from /var/lib/cloud/instance/obj.pkl 2020-07-16 13:02:52,570 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) 2020-07-16 13:02:52,570 - util.py[DEBUG]: Read 8 bytes from /run/cloud-init/.instance-id 2020-07-16 13:02:52,570 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net] 2020-07-16 13:02:52,570 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net] 2020-07-16 13:02:52,570 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2020-07-16 13:02:52,570 - util.py[DEBUG]: Read 2637 bytes from /etc/cloud/cloud.cfg 2020-07-16 13:02:52,570 - util.py[DEBUG]: Attempting to load yaml from string of length 2637 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,576 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2020-07-16 13:02:52,576 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2020-07-16 13:02:52,576 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,579 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) 2020-07-16 13:02:52,579 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg 2020-07-16 13:02:52,579 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,579 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2020-07-16 13:02:52,580 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg 2020-07-16 13:02:52,580 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,580 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,580 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:52,580 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2020-07-16 13:02:52,580 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/nocloud' 2020-07-16 13:02:52,581 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/nocloud/datasource (quiet=False) 2020-07-16 13:02:52,581 - util.py[DEBUG]: Read 65 bytes from /var/lib/cloud/instances/nocloud/datasource 2020-07-16 13:02:52,581 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/datasource - wb: [644] 65 bytes 2020-07-16 13:02:52,581 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 65 bytes 2020-07-16 13:02:52,581 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2020-07-16 13:02:52,581 - util.py[DEBUG]: Read 8 bytes from /var/lib/cloud/data/instance-id 2020-07-16 13:02:52,582 - stages.py[DEBUG]: previous iid found to be nocloud 2020-07-16 13:02:52,582 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 8 bytes 2020-07-16 13:02:52,582 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 8 bytes 2020-07-16 13:02:52,582 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 8 bytes 2020-07-16 13:02:52,583 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 4776 bytes 2020-07-16 13:02:52,583 - main.py[DEBUG]: [net] init will now be targeting instance id: nocloud. new=False 2020-07-16 13:02:52,583 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2020-07-16 13:02:52,583 - util.py[DEBUG]: Read 2637 bytes from /etc/cloud/cloud.cfg 2020-07-16 13:02:52,583 - util.py[DEBUG]: Attempting to load yaml from string of length 2637 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,589 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2020-07-16 13:02:52,589 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2020-07-16 13:02:52,589 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,591 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) 2020-07-16 13:02:52,591 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg 2020-07-16 13:02:52,592 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,592 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2020-07-16 13:02:52,592 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg 2020-07-16 13:02:52,592 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,593 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,593 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:52,593 - util.py[DEBUG]: Reading from /sys/class/net/enp1s0/address (quiet=False) 2020-07-16 13:02:52,593 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enp1s0/address 2020-07-16 13:02:52,593 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2020-07-16 13:02:52,594 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2020-07-16 13:02:52,594 - __init__.py[DEBUG]: Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net] not updated for events: System boot 2020-07-16 13:02:52,594 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update on 'System boot' event 2020-07-16 13:02:52,594 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'main': {'match': {'macaddress': '52:54:00:8c:fc:10'}, 'addresses': ['192.168.122.100'], 'gateway4': '192.168.122.1'}}} 2020-07-16 13:02:52,594 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:52,594 - __init__.py[DEBUG]: no interfaces to rename 2020-07-16 13:02:52,594 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource 2020-07-16 13:02:52,594 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource 2020-07-16 13:02:52,594 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/user-data.txt - wb: [600] 656 bytes 2020-07-16 13:02:52,595 - util.py[DEBUG]: Attempting to load yaml from string of length 656 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,598 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/user-data.txt.i - wb: [600] 961 bytes 2020-07-16 13:02:52,598 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/vendor-data.txt - wb: [600] 0 bytes 2020-07-16 13:02:52,599 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/vendor-data.txt.i - wb: [600] 308 bytes 2020-07-16 13:02:52,599 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/consume_data - wb: [644] 24 bytes 2020-07-16 13:02:52,599 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/consume_data'>) 2020-07-16 13:02:52,599 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data 2020-07-16 13:02:52,600 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] 2020-07-16 13:02:52,600 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] 2020-07-16 13:02:52,600 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] 2020-07-16 13:02:52,600 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] 2020-07-16 13:02:52,600 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} 2020-07-16 13:02:52,600 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance 2020-07-16 13:02:52,600 - util.py[DEBUG]: Attempting to load yaml from string of length 656 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,602 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] 2020-07-16 13:02:52,602 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance 2020-07-16 13:02:52,603 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/cloud-config.txt - wb: [600] 714 bytes 2020-07-16 13:02:52,603 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,603 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,603 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance 2020-07-16 13:02:52,603 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance 2020-07-16 13:02:52,603 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data 2020-07-16 13:02:52,603 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data 2020-07-16 13:02:52,603 - stages.py[DEBUG]: no vendordata from datasource 2020-07-16 13:02:52,603 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data 2020-07-16 13:02:52,604 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2020-07-16 13:02:52,604 - util.py[DEBUG]: Read 2637 bytes from /etc/cloud/cloud.cfg 2020-07-16 13:02:52,604 - util.py[DEBUG]: Attempting to load yaml from string of length 2637 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,610 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2020-07-16 13:02:52,610 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2020-07-16 13:02:52,610 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,612 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False) 2020-07-16 13:02:52,612 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg 2020-07-16 13:02:52,612 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,613 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2020-07-16 13:02:52,613 - util.py[DEBUG]: Read 35 bytes from /run/cloud-init/cloud.cfg 2020-07-16 13:02:52,613 - util.py[DEBUG]: Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,613 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,613 - util.py[DEBUG]: loaded blob returned None, returning default. 2020-07-16 13:02:52,613 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2020-07-16 13:02:52,613 - util.py[DEBUG]: Read 714 bytes from /var/lib/cloud/instance/cloud-config.txt 2020-07-16 13:02:52,613 - util.py[DEBUG]: Attempting to load yaml from string of length 714 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,615 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2020-07-16 13:02:52,615 - util.py[DEBUG]: Read 714 bytes from /var/lib/cloud/instance/cloud-config.txt 2020-07-16 13:02:52,615 - util.py[DEBUG]: Attempting to load yaml from string of length 714 with allowed root types (<class 'dict'>,) 2020-07-16 13:02:52,624 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource 2020-07-16 13:02:52,625 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 6971 bytes 2020-07-16 13:02:52,625 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource 2020-07-16 13:02:52,626 - main.py[DEBUG]: no di_report found in config. 2020-07-16 13:02:52,655 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:52,656 - 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 2020-07-16 13:02:52,656 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always 2020-07-16 13:02:52,656 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c7bdab20>) 2020-07-16 13:02:52,656 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names 2020-07-16 13:02:52,656 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully 2020-07-16 13:02:52,656 - 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 2020-07-16 13:02:52,656 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance 2020-07-16 13:02:52,656 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_seed_random - wb: [644] 24 bytes 2020-07-16 13:02:52,657 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_seed_random'>) 2020-07-16 13:02:52,657 - cc_seed_random.py[DEBUG]: no command provided 2020-07-16 13:02:52,657 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully 2020-07-16 13:02:52,657 - 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 2020-07-16 13:02:52,657 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always 2020-07-16 13:02:52,657 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c7b2d3d0>) 2020-07-16 13:02:52,657 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration 2020-07-16 13:02:52,657 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully 2020-07-16 13:02:52,657 - 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 2020-07-16 13:02:52,657 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance 2020-07-16 13:02:52,657 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_write_files - wb: [644] 24 bytes 2020-07-16 13:02:52,658 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_write_files'>) 2020-07-16 13:02:52,658 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration 2020-07-16 13:02:52,658 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully 2020-07-16 13:02:52,658 - 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 2020-07-16 13:02:52,658 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always 2020-07-16 13:02:52,658 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c7b2d250>) 2020-07-16 13:02:52,658 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} 2020-07-16 13:02:52,658 - util.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,663 - util.py[DEBUG]: Reading from /proc/414/mountinfo (quiet=False) 2020-07-16 13:02:52,663 - util.py[DEBUG]: Read 3126 bytes from /proc/414/mountinfo 2020-07-16 13:02:52,663 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,667 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,669 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,670 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) 2020-07-16 13:02:52,670 - util.py[DEBUG]: Read 145 bytes from /proc/1/environ 2020-07-16 13:02:52,670 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) 2020-07-16 13:02:52,670 - util.py[DEBUG]: Read 1035 bytes from /proc/self/status 2020-07-16 13:02:52,670 - util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False) 2020-07-16 13:02:52,670 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition 2020-07-16 13:02:52,670 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:02.2/0000:03:00.0/virtio1/host0/target0:0:0/0:0:0:0/block/sda/dev (quiet=False) 2020-07-16 13:02:52,670 - util.py[DEBUG]: Read 4 bytes from /sys/devices/pci0000:00/0000:00:02.2/0000:03:00.0/virtio1/host0/target0:0:0/0:0:0:0/block/sda/dev 2020-07-16 13:02:52,670 - util.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,695 - util.py[DEBUG]: resize_devices took 0.032 seconds 2020-07-16 13:02:52,695 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/sda, 1) 2020-07-16 13:02:52,695 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully 2020-07-16 13:02:52,695 - 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 2020-07-16 13:02:52,695 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always 2020-07-16 13:02:52,695 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c7b2d340>) 2020-07-16 13:02:52,735 - util.py[DEBUG]: Reading from /proc/414/mountinfo (quiet=False) 2020-07-16 13:02:52,736 - util.py[DEBUG]: Read 3126 bytes from /proc/414/mountinfo 2020-07-16 13:02:52,736 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/sda1 mnt_point=/ path=/ 2020-07-16 13:02:52,736 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,740 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,742 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,743 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False) 2020-07-16 13:02:52,743 - util.py[DEBUG]: Read 145 bytes from /proc/1/environ 2020-07-16 13:02:52,743 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False) 2020-07-16 13:02:52,743 - util.py[DEBUG]: Read 1035 bytes from /proc/self/status 2020-07-16 13:02:52,743 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/sda1 2020-07-16 13:02:52,743 - util.py[DEBUG]: Running command ('resize2fs', '/dev/sda1') with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,768 - util.py[DEBUG]: Resizing took 0.025 seconds 2020-07-16 13:02:52,768 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) 2020-07-16 13:02:52,768 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully 2020-07-16 13:02:52,768 - 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 2020-07-16 13:02:52,768 - handlers.py[DEBUG]: start: init-network/config-disk_setup: running config-disk_setup with frequency once-per-instance 2020-07-16 13:02:52,768 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_disk_setup - wb: [644] 24 bytes 2020-07-16 13:02:52,769 - helpers.py[DEBUG]: Running config-disk_setup using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_disk_setup'>) 2020-07-16 13:02:52,769 - handlers.py[DEBUG]: finish: init-network/config-disk_setup: SUCCESS: config-disk_setup ran successfully 2020-07-16 13:02:52,769 - 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 2020-07-16 13:02:52,769 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance 2020-07-16 13:02:52,769 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_mounts - wb: [644] 23 bytes 2020-07-16 13:02:52,769 - helpers.py[DEBUG]: Running config-mounts using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_mounts'>) 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: mounts configuration is [] 2020-07-16 13:02:52,770 - util.py[DEBUG]: Reading from /etc/fstab (quiet=False) 2020-07-16 13:02:52,770 - util.py[DEBUG]: Read 187 bytes from /etc/fstab 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: changed default device swap => None 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: no need to setup swap 2020-07-16 13:02:52,770 - cc_mounts.py[DEBUG]: No modifications to fstab needed 2020-07-16 13:02:52,770 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully 2020-07-16 13:02:52,770 - 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 2020-07-16 13:02:52,770 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance 2020-07-16 13:02:52,770 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_set_hostname - wb: [644] 24 bytes 2020-07-16 13:02:52,771 - helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_set_hostname'>) 2020-07-16 13:02:52,771 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) 2020-07-16 13:02:52,771 - util.py[DEBUG]: Read 109 bytes from /etc/hosts 2020-07-16 13:02:52,771 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) 2020-07-16 13:02:52,771 - util.py[DEBUG]: Read 109 bytes from /etc/hosts 2020-07-16 13:02:52,771 - cc_set_hostname.py[DEBUG]: Setting the hostname to debian (debian) 2020-07-16 13:02:52,771 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2020-07-16 13:02:52,771 - util.py[DEBUG]: Read 7 bytes from /etc/hostname 2020-07-16 13:02:52,771 - util.py[DEBUG]: Writing to /etc/hostname - wb: [644] 7 bytes 2020-07-16 13:02:52,771 - __init__.py[DEBUG]: Non-persistently setting the system hostname to debian 2020-07-16 13:02:52,772 - util.py[DEBUG]: Running command ['hostname', 'debian'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:52,773 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmp9dp42_7k) - w: [644] 45 bytes/chars 2020-07-16 13:02:52,774 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully 2020-07-16 13:02:52,774 - 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 2020-07-16 13:02:52,774 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always 2020-07-16 13:02:52,774 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c8854850>) 2020-07-16 13:02:52,774 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) 2020-07-16 13:02:52,774 - util.py[DEBUG]: Read 109 bytes from /etc/hosts 2020-07-16 13:02:52,774 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False) 2020-07-16 13:02:52,774 - util.py[DEBUG]: Read 109 bytes from /etc/hosts 2020-07-16 13:02:52,774 - cc_update_hostname.py[DEBUG]: Updating hostname to debian (debian) 2020-07-16 13:02:52,774 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2020-07-16 13:02:52,774 - util.py[DEBUG]: Read 7 bytes from /etc/hostname 2020-07-16 13:02:52,774 - __init__.py[DEBUG]: Attempting to update hostname to debian in 1 files 2020-07-16 13:02:52,774 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) 2020-07-16 13:02:52,775 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 7 bytes 2020-07-16 13:02:52,775 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully 2020-07-16 13:02:52,775 - 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 2020-07-16 13:02:52,775 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always 2020-07-16 13:02:52,775 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7fd9c8854790>) 2020-07-16 13:02:52,775 - cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts 2020-07-16 13:02:52,775 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully 2020-07-16 13:02:52,775 - 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 2020-07-16 13:02:52,775 - handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance 2020-07-16 13:02:52,776 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_ca_certs - wb: [644] 24 bytes 2020-07-16 13:02:52,776 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_ca_certs'>) 2020-07-16 13:02:52,776 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration 2020-07-16 13:02:52,776 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully 2020-07-16 13:02:52,776 - 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 2020-07-16 13:02:52,776 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance 2020-07-16 13:02:52,776 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_rsyslog - wb: [644] 23 bytes 2020-07-16 13:02:52,776 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_rsyslog'>) 2020-07-16 13:02:52,777 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration 2020-07-16 13:02:52,777 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully 2020-07-16 13:02:52,777 - 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 2020-07-16 13:02:52,777 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance 2020-07-16 13:02:52,777 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_users_groups - wb: [644] 24 bytes 2020-07-16 13:02:52,777 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_users_groups'>) 2020-07-16 13:02:52,777 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) 2020-07-16 13:02:52,777 - util.py[DEBUG]: Read 200 bytes from /etc/os-release 2020-07-16 13:02:52,778 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) 2020-07-16 13:02:52,778 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini 2020-07-16 13:02:52,778 - __init__.py[DEBUG]: Adding user foo 2020-07-16 13:02:52,778 - util.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'foo', '--password', 'REDACTED', '--shell', '/bin/bash', '-m'] 2020-07-16 13:02:52,924 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) 2020-07-16 13:02:52,925 - util.py[DEBUG]: Read 669 bytes from /etc/sudoers 2020-07-16 13:02:52,926 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 116 bytes 2020-07-16 13:02:52,926 - util.py[DEBUG]: Changing the ownership of /home/foo/.ssh to 1000:1000 2020-07-16 13:02:52,927 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2020-07-16 13:02:52,927 - util.py[DEBUG]: Read 3311 bytes from /etc/ssh/sshd_config 2020-07-16 13:02:52,928 - util.py[DEBUG]: Writing to /home/foo/.ssh/authorized_keys - wb: [600] 408 bytes 2020-07-16 13:02:52,928 - util.py[DEBUG]: Changing the ownership of /home/foo/.ssh/authorized_keys to 1000:1000 2020-07-16 13:02:52,928 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully 2020-07-16 13:02:52,928 - 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 2020-07-16 13:02:52,928 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance 2020-07-16 13:02:52,928 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_ssh - wb: [644] 24 bytes 2020-07-16 13:02:52,929 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_ssh'>) 2020-07-16 13:02:52,929 - 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) 2020-07-16 13:02:53,120 - 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) 2020-07-16 13:02:53,181 - 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) 2020-07-16 13:02:53,185 - 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) 2020-07-16 13:02:53,190 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) 2020-07-16 13:02:53,190 - util.py[DEBUG]: Read 93 bytes from /etc/ssh/ssh_host_ed25519_key.pub 2020-07-16 13:02:53,190 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) 2020-07-16 13:02:53,190 - util.py[DEBUG]: Read 173 bytes from /etc/ssh/ssh_host_ecdsa_key.pub 2020-07-16 13:02:53,190 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) 2020-07-16 13:02:53,190 - util.py[DEBUG]: Read 565 bytes from /etc/ssh/ssh_host_rsa_key.pub 2020-07-16 13:02:53,191 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 2020-07-16 13:02:53,191 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2020-07-16 13:02:53,191 - util.py[DEBUG]: Read 3311 bytes from /etc/ssh/sshd_config 2020-07-16 13:02:53,191 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes 2020-07-16 13:02:53,192 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 2020-07-16 13:02:53,192 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully 2020-07-16 13:02:53,192 - main.py[DEBUG]: Ran 15 modules with 0 failures 2020-07-16 13:02:53,192 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpz1o16gzo) - w: [644] 545 bytes/chars 2020-07-16 13:02:53,192 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2020-07-16 13:02:53,193 - util.py[DEBUG]: Read 12 bytes from /proc/uptime 2020-07-16 13:02:53,193 - util.py[DEBUG]: cloud-init mode 'init' took 0.665 seconds (0.67) 2020-07-16 13:02:53,193 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources 2020-07-16 13:02:53,554 - util.py[DEBUG]: Cloud-init v. 20.2 running 'modules:config' at Thu, 16 Jul 2020 13:02:53 +0000. Up 12.67 seconds. 2020-07-16 13:02:53,570 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:53,570 - 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 2020-07-16 13:02:53,571 - handlers.py[DEBUG]: start: modules-config/config-emit_upstart: running config-emit_upstart with frequency always 2020-07-16 13:02:53,571 - helpers.py[DEBUG]: Running config-emit_upstart using lock (<cloudinit.helpers.DummyLock object at 0x7f00e776b580>) 2020-07-16 13:02:53,571 - cc_emit_upstart.py[DEBUG]: no /sbin/initctl located 2020-07-16 13:02:53,571 - cc_emit_upstart.py[DEBUG]: not upstart system, 'emit_upstart' disabled 2020-07-16 13:02:53,571 - handlers.py[DEBUG]: finish: modules-config/config-emit_upstart: SUCCESS: config-emit_upstart ran successfully 2020-07-16 13:02:53,571 - 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 2020-07-16 13:02:53,571 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance 2020-07-16 13:02:53,571 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_ssh_import_id - wb: [644] 23 bytes 2020-07-16 13:02:53,571 - helpers.py[DEBUG]: Running config-ssh-import-id using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_ssh_import_id'>) 2020-07-16 13:02:53,572 - cc_ssh_import_id.py[DEBUG]: User foo is not configured for ssh_import_id 2020-07-16 13:02:53,572 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id ran successfully 2020-07-16 13:02:53,572 - 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 2020-07-16 13:02:53,572 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance 2020-07-16 13:02:53,572 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_locale - wb: [644] 24 bytes 2020-07-16 13:02:53,572 - helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_locale'>) 2020-07-16 13:02:53,572 - util.py[DEBUG]: Reading from /etc/default/locale (quiet=False) 2020-07-16 13:02:53,572 - util.py[DEBUG]: Read 13 bytes from /etc/default/locale 2020-07-16 13:02:53,573 - cc_locale.py[DEBUG]: Setting locale to C.UTF-8 2020-07-16 13:02:53,573 - debian.py[DEBUG]: System has 'LANG=C.UTF-8' requested 'C.UTF-8', skipping regeneration. 2020-07-16 13:02:53,573 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully 2020-07-16 13:02:53,573 - 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 2020-07-16 13:02:53,573 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance 2020-07-16 13:02:53,573 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_set_passwords - wb: [644] 24 bytes 2020-07-16 13:02:53,573 - helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_set_passwords'>) 2020-07-16 13:02:53,573 - cc_set_passwords.py[DEBUG]: Leaving SSH config 'PasswordAuthentication' unchanged. ssh_pwauth=None 2020-07-16 13:02:53,573 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully 2020-07-16 13:02:53,573 - 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 2020-07-16 13:02:53,573 - handlers.py[DEBUG]: start: modules-config/config-grub-dpkg: running config-grub-dpkg with frequency once-per-instance 2020-07-16 13:02:53,574 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_grub_dpkg - wb: [644] 24 bytes 2020-07-16 13:02:53,574 - helpers.py[DEBUG]: Running config-grub-dpkg using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_grub_dpkg'>) 2020-07-16 13:02:53,574 - cc_grub_dpkg.py[DEBUG]: Setting grub debconf-set-selections with '/dev/sda','false' 2020-07-16 13:02:53,574 - util.py[DEBUG]: Running command ['debconf-set-selections'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:53,716 - handlers.py[DEBUG]: finish: modules-config/config-grub-dpkg: SUCCESS: config-grub-dpkg ran successfully 2020-07-16 13:02:53,716 - 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 2020-07-16 13:02:53,716 - handlers.py[DEBUG]: start: modules-config/config-apt-pipelining: running config-apt-pipelining with frequency once-per-instance 2020-07-16 13:02:53,716 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_apt_pipelining - wb: [644] 24 bytes 2020-07-16 13:02:53,717 - helpers.py[DEBUG]: Running config-apt-pipelining using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_apt_pipelining'>) 2020-07-16 13:02:53,717 - handlers.py[DEBUG]: finish: modules-config/config-apt-pipelining: SUCCESS: config-apt-pipelining ran successfully 2020-07-16 13:02:53,717 - 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 2020-07-16 13:02:53,717 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance 2020-07-16 13:02:53,717 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_apt_configure - wb: [644] 23 bytes 2020-07-16 13:02:53,717 - helpers.py[DEBUG]: Running config-apt-configure using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_apt_configure'>) 2020-07-16 13:02:53,717 - cc_apt_configure.py[DEBUG]: apt config: convert V2 to V3 format for keys 'apt_preserve_sources_list' 2020-07-16 13:02:53,717 - cc_apt_configure.py[DEBUG]: debconf_selections was not set in config 2020-07-16 13:02:53,717 - cc_apt_configure.py[DEBUG]: handling apt config: {'preserve_sources_list': True} 2020-07-16 13:02:53,717 - util.py[DEBUG]: Running command ['lsb_release', '--all'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:53,774 - util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:53,777 - cc_apt_configure.py[DEBUG]: got primary mirror: None 2020-07-16 13:02:53,777 - cc_apt_configure.py[DEBUG]: got security mirror: None 2020-07-16 13:02:53,777 - util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:53,779 - __init__.py[DEBUG]: filtered distro mirror info: {'primary': 'http://deb.debian.org/debian', 'security': 'http://security.debian.org/'} 2020-07-16 13:02:53,779 - 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'} 2020-07-16 13:02:53,780 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure ran successfully 2020-07-16 13:02:53,780 - 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 2020-07-16 13:02:53,780 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance 2020-07-16 13:02:53,780 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_ntp - wb: [644] 24 bytes 2020-07-16 13:02:53,780 - helpers.py[DEBUG]: Running config-ntp using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_ntp'>) 2020-07-16 13:02:53,780 - cc_ntp.py[DEBUG]: Skipping module named ntp, not present or disabled by cfg 2020-07-16 13:02:53,780 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully 2020-07-16 13:02:53,780 - 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 2020-07-16 13:02:53,781 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance 2020-07-16 13:02:53,781 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_timezone - wb: [644] 24 bytes 2020-07-16 13:02:53,781 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_timezone'>) 2020-07-16 13:02:53,781 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified 2020-07-16 13:02:53,781 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully 2020-07-16 13:02:53,781 - 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 2020-07-16 13:02:53,781 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always 2020-07-16 13:02:53,781 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x7f00e7787fd0>) 2020-07-16 13:02:53,781 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled 2020-07-16 13:02:53,781 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully 2020-07-16 13:02:53,781 - 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 2020-07-16 13:02:53,782 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance 2020-07-16 13:02:53,782 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_runcmd - wb: [644] 24 bytes 2020-07-16 13:02:53,782 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_runcmd'>) 2020-07-16 13:02:53,782 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration 2020-07-16 13:02:53,782 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully 2020-07-16 13:02:53,782 - 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 2020-07-16 13:02:53,782 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance 2020-07-16 13:02:53,782 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_byobu - wb: [644] 24 bytes 2020-07-16 13:02:53,782 - helpers.py[DEBUG]: Running config-byobu using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_byobu'>) 2020-07-16 13:02:53,782 - cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found 2020-07-16 13:02:53,783 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu ran successfully 2020-07-16 13:02:53,783 - main.py[DEBUG]: Ran 12 modules with 0 failures 2020-07-16 13:02:53,783 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpw796ivhj) - w: [644] 572 bytes/chars 2020-07-16 13:02:53,783 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2020-07-16 13:02:53,783 - util.py[DEBUG]: Read 12 bytes from /proc/uptime 2020-07-16 13:02:53,783 - util.py[DEBUG]: cloud-init mode 'modules' took 0.277 seconds (0.28) 2020-07-16 13:02:53,783 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config 2020-07-16 13:02:54,047 - util.py[DEBUG]: Cloud-init v. 20.2 running 'modules:final' at Thu, 16 Jul 2020 13:02:54 +0000. Up 13.18 seconds. 2020-07-16 13:02:54,062 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'> 2020-07-16 13:02:54,063 - 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 2020-07-16 13:02:54,063 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance 2020-07-16 13:02:54,063 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_package_update_upgrade_install - wb: [644] 24 bytes 2020-07-16 13:02:54,063 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_package_update_upgrade_install'>) 2020-07-16 13:02:54,064 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully 2020-07-16 13:02:54,064 - 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 2020-07-16 13:02:54,064 - handlers.py[DEBUG]: start: modules-final/config-fan: running config-fan with frequency once-per-instance 2020-07-16 13:02:54,064 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_fan - wb: [644] 24 bytes 2020-07-16 13:02:54,064 - helpers.py[DEBUG]: Running config-fan using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_fan'>) 2020-07-16 13:02:54,064 - cc_fan.py[DEBUG]: fan: no 'fan' config entry. disabling 2020-07-16 13:02:54,064 - handlers.py[DEBUG]: finish: modules-final/config-fan: SUCCESS: config-fan ran successfully 2020-07-16 13:02:54,064 - 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 2020-07-16 13:02:54,064 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance 2020-07-16 13:02:54,065 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_puppet - wb: [644] 24 bytes 2020-07-16 13:02:54,065 - helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_puppet'>) 2020-07-16 13:02:54,065 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found 2020-07-16 13:02:54,065 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet ran successfully 2020-07-16 13:02:54,065 - 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 2020-07-16 13:02:54,065 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency once-per-instance 2020-07-16 13:02:54,065 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_chef - wb: [644] 24 bytes 2020-07-16 13:02:54,065 - helpers.py[DEBUG]: Running config-chef using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_chef'>) 2020-07-16 13:02:54,065 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration 2020-07-16 13:02:54,066 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully 2020-07-16 13:02:54,066 - 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 2020-07-16 13:02:54,066 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance 2020-07-16 13:02:54,066 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_salt_minion - wb: [644] 24 bytes 2020-07-16 13:02:54,066 - helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_salt_minion'>) 2020-07-16 13:02:54,066 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration 2020-07-16 13:02:54,066 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion ran successfully 2020-07-16 13:02:54,066 - 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 2020-07-16 13:02:54,066 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance 2020-07-16 13:02:54,066 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_mcollective - wb: [644] 23 bytes 2020-07-16 13:02:54,067 - helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_mcollective'>) 2020-07-16 13:02:54,067 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration 2020-07-16 13:02:54,067 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective ran successfully 2020-07-16 13:02:54,067 - 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 2020-07-16 13:02:54,067 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance 2020-07-16 13:02:54,067 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_rightscale_userdata - wb: [644] 24 bytes 2020-07-16 13:02:54,067 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_rightscale_userdata'>) 2020-07-16 13:02:54,067 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata 2020-07-16 13:02:54,067 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully 2020-07-16 13:02:54,067 - 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 2020-07-16 13:02:54,068 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance 2020-07-16 13:02:54,068 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_scripts_vendor - wb: [644] 22 bytes 2020-07-16 13:02:54,068 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_scripts_vendor'>) 2020-07-16 13:02:54,068 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully 2020-07-16 13:02:54,068 - 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 2020-07-16 13:02:54,068 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once 2020-07-16 13:02:54,068 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 23 bytes 2020-07-16 13:02:54,068 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>) 2020-07-16 13:02:54,069 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully 2020-07-16 13:02:54,069 - 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 2020-07-16 13:02:54,069 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always 2020-07-16 13:02:54,069 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7f3786345c10>) 2020-07-16 13:02:54,069 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully 2020-07-16 13:02:54,069 - 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 2020-07-16 13:02:54,069 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance 2020-07-16 13:02:54,069 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_scripts_per_instance - wb: [644] 24 bytes 2020-07-16 13:02:54,069 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_scripts_per_instance'>) 2020-07-16 13:02:54,069 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully 2020-07-16 13:02:54,069 - 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 2020-07-16 13:02:54,070 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance 2020-07-16 13:02:54,070 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_scripts_user - wb: [644] 24 bytes 2020-07-16 13:02:54,070 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_scripts_user'>) 2020-07-16 13:02:54,070 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully 2020-07-16 13:02:54,070 - 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 2020-07-16 13:02:54,070 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance 2020-07-16 13:02:54,070 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_ssh_authkey_fingerprints - wb: [644] 23 bytes 2020-07-16 13:02:54,071 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_ssh_authkey_fingerprints'>) 2020-07-16 13:02:54,071 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2020-07-16 13:02:54,071 - util.py[DEBUG]: Read 3311 bytes from /etc/ssh/sshd_config 2020-07-16 13:02:54,071 - util.py[DEBUG]: Reading from /home/foo/.ssh/authorized_keys (quiet=False) 2020-07-16 13:02:54,071 - util.py[DEBUG]: Read 408 bytes from /home/foo/.ssh/authorized_keys 2020-07-16 13:02:54,078 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints ran successfully 2020-07-16 13:02:54,078 - 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 2020-07-16 13:02:54,079 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance 2020-07-16 13:02:54,079 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_keys_to_console - wb: [644] 22 bytes 2020-07-16 13:02:54,079 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_keys_to_console'>) 2020-07-16 13:02:54,079 - util.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True) 2020-07-16 13:02:54,104 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully 2020-07-16 13:02:54,104 - 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 2020-07-16 13:02:54,105 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance 2020-07-16 13:02:54,105 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_phone_home - wb: [644] 24 bytes 2020-07-16 13:02:54,105 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_phone_home'>) 2020-07-16 13:02:54,105 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found 2020-07-16 13:02:54,105 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully 2020-07-16 13:02:54,105 - 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 2020-07-16 13:02:54,106 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always 2020-07-16 13:02:54,106 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7f3786333580>) 2020-07-16 13:02:54,106 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2020-07-16 13:02:54,106 - util.py[DEBUG]: Read 12 bytes from /proc/uptime 2020-07-16 13:02:54,108 - util.py[DEBUG]: Cloud-init v. 20.2 finished at Thu, 16 Jul 2020 13:02:54 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net]. Up 13.27 seconds 2020-07-16 13:02:54,108 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 50 bytes 2020-07-16 13:02:54,109 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully 2020-07-16 13:02:54,109 - 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 2020-07-16 13:02:54,109 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance 2020-07-16 13:02:54,109 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/nocloud/sem/config_power_state_change - wb: [644] 24 bytes 2020-07-16 13:02:54,109 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/nocloud/sem/config_power_state_change'>) 2020-07-16 13:02:54,109 - cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing 2020-07-16 13:02:54,110 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully 2020-07-16 13:02:54,110 - main.py[DEBUG]: Ran 17 modules with 0 failures 2020-07-16 13:02:54,110 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp4rygq1gf) - w: [644] 600 bytes/chars 2020-07-16 13:02:54,110 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmpp8tbzbyn) - w: [644] 96 bytes/chars 2020-07-16 13:02:54,110 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' 2020-07-16 13:02:54,110 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2020-07-16 13:02:54,110 - util.py[DEBUG]: Read 12 bytes from /proc/uptime 2020-07-16 13:02:54,110 - util.py[DEBUG]: cloud-init mode 'modules' took 0.100 seconds (0.11) 2020-07-16 13:02:54,111 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final
Cloud-init v. 20.2 running 'init-local' at Thu, 16 Jul 2020 13:02:42 +0000. Up 1.99 seconds. Cloud-init v. 20.2 running 'init' at Thu, 16 Jul 2020 13:02:52 +0000. Up 11.70 seconds. ci-info: ++++++++++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++++++++ ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ ci-info: | enp1s0 | True | 192.168.122.153 | 255.255.255.0 | global | 52:54:00:8c:fc:10 | ci-info: | enp1s0 | True | fe80::5054:ff:fe8c:fc10/64 | . | link | 52:54:00:8c:fc:10 | ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | ci-info: | lo | True | ::1/128 | . | host | . | ci-info: +--------+------+----------------------------+---------------+--------+-------------------+ ci-info: +++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++ ci-info: +-------+---------------+---------------+---------------+-----------+-------+ ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | ci-info: +-------+---------------+---------------+---------------+-----------+-------+ ci-info: | 0 | 0.0.0.0 | 192.168.122.1 | 0.0.0.0 | enp1s0 | UG | ci-info: | 1 | 192.168.122.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | ci-info: +-------+---------------+---------------+---------------+-----------+-------+ ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ ci-info: +-------+-------------+---------+-----------+-------+ ci-info: | Route | Destination | Gateway | Interface | Flags | ci-info: +-------+-------------+---------+-----------+-------+ ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | ci-info: | 3 | local | :: | enp1s0 | U | ci-info: | 4 | ff00::/8 | :: | enp1s0 | U | ci-info: +-------+-------------+---------+-----------+-------+ Generating public/private rsa key pair. Your identification has been saved in /etc/ssh/ssh_host_rsa_key Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub The key fingerprint is: SHA256:FagMh6dgMhIWQ+/W3HQZPhuHmJc1aYA7O5Uex89TwE8 root@debian The key's randomart image is: +---[RSA 3072]----+ |o*. . .+ooo | |= = o o.= *+.o E | |.+ o * =.X=. + | | . + =oo==o o | | o o .S.o o . | | . o . + | | . . | | | | | +----[SHA256]-----+ Generating public/private dsa key pair. Your identification has been saved in /etc/ssh/ssh_host_dsa_key Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub The key fingerprint is: SHA256:3dcd8n+UKYCz49Am8SOPGYxtzBZtlBG6iqKTbX4NA/M root@debian The key's randomart image is: +---[DSA 1024]----+ | ++ | | +.. | | + = . . . | | o * B.o.. o.=| | + . &SB. ...++| | E + @ o ....| | + . = o o o| |+ + . . .| |.+.. | +----[SHA256]-----+ Generating public/private ecdsa key pair. Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub The key fingerprint is: SHA256:WgL3onnzOgBeNdaF/U0/ejhw682+4FOpvjWKmS5YdPY root@debian The key's randomart image is: +---[ECDSA 256]---+ | . +. | | + o . . | | .o.. . o . | | . .o . . +.....| | . o o S o + + o| | . .o = . E + | | o.+ o ..Bo | | ..+ . =++o.| | .o. o=.=+o.| +----[SHA256]-----+ Generating public/private ed25519 key pair. Your identification has been saved in /etc/ssh/ssh_host_ed25519_key Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub The key fingerprint is: SHA256:01UremZS8qVLS/9+tgQNXOtGY2761DSD+uW/tUonymA root@debian The key's randomart image is: +--[ED25519 256]--+ | .. | | .....| | . +oo= | | . * +O .| | S + Oo O.| | . B.+=.+| | E .o+.=o| | . o + O.=| | o o.OO| +----[SHA256]-----+ Cloud-init v. 20.2 running 'modules:config' at Thu, 16 Jul 2020 13:02:53 +0000. Up 12.67 seconds. Cloud-init v. 20.2 running 'modules:final' at Thu, 16 Jul 2020 13:02:54 +0000. Up 13.18 seconds. Cloud-init v. 20.2 finished at Thu, 16 Jul 2020 13:02:54 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net]. Up 13.27 seconds
#cloud-config users: - name: foo sudo: ALL=(ALL) NOPASSWD:ALL passwd: $6$AUcK0WG1wk3IvvJQ$jIhwiJkvlf8oSTuviqWiCEl7mXfmlIxluDbFh3XV2BdpeCYGPMCwUjvLzOr92rq4UZRp/O2.iFR33xYr5K4WR/ lock_passwd: false shell: /bin/bash ssh_authorized_keys: - ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDsigSBPsp9N6ZjhTl2fN5dpm00B8pIQjBMZALtg42qyeC4tjX2LfZfeE1iCqZeytpFqCzWiTMN30J24tsGTQWbXbvmW7wDM6pkzT71BnAXED4X6Woc064KFahKi3dQvn5PWxH2FFqXcIGS/+vWhOMzcg2B8u8llQ7lkckg2E6LD48EJnbfsuGoqMADFxWtw5itBzGyAQj94i98tbRNuomiLotSxiB7s7XCtvI8SYehfQhGsFBDKQ3dgzd3wDQNEz1v1RgwlOcDFvTmodShEI4FZgl5tO4dbzVJR5F0ddUW6KHLlK1BlyswVDuYWnnhv5Qahg6eJn323hS/eRlkcp7l sven.bartsc...@credativ.de
meta-data
Description: inode/empty
version: 2 ethernets: main: match: macaddress: "52:54:00:8c:fc:10" addresses: ["192.168.122.100"] gateway4: "192.168.122.1"