Created
February 6, 2023 20:08
-
-
Save lukasz-zaroda/f65cec1a198f02febab167c2fd2c6984 to your computer and use it in GitHub Desktop.
almalinux/9/cloud failed networking on creation cloud-init log
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
2023-02-06 19:20:23,213 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'init-local' at Mon, 06 Feb 2023 19:20:23 +0000. Up 0.80 seconds. | |
2023-02-06 19:20:23,213 - main.py[DEBUG]: No kernel command line url found. | |
2023-02-06 19:20:23,213 - main.py[DEBUG]: Closing stdin. | |
2023-02-06 19:20:23,214 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2023-02-06 19:20:23,214 - util.py[DEBUG]: Writing to /var/lib/cloud/data/python-version - wb: [644] 3 bytes | |
2023-02-06 19:20:23,214 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished | |
2023-02-06 19:20:23,214 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net | |
2023-02-06 19:20:23,214 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] | |
2023-02-06 19:20:23,214 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2023-02-06 19:20:23,214 - stages.py[DEBUG]: no cache found | |
2023-02-06 19:20:23,214 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found | |
2023-02-06 19:20:23,215 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2023-02-06 19:20:23,216 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:23,216 - __init__.py[DEBUG]: Looking for data source in: ['NoCloud', 'LXD', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] | |
2023-02-06 19:20:23,217 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceNoCloud', 'DataSourceLXD'] | |
2023-02-06 19:20:23,217 - handlers.py[DEBUG]: start: init-local/search-NoCloud: searching for local data from DataSourceNoCloud | |
2023-02-06 19:20:23,217 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceNoCloud.DataSourceNoCloud'> | |
2023-02-06 19:20:23,217 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: boot-new-instance | |
2023-02-06 19:20:23,217 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) | |
2023-02-06 19:20:23,217 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/user-data (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Read 985 bytes from /var/lib/cloud/seed/nocloud-net/user-data | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Read 76 bytes from /var/lib/cloud/seed/nocloud-net/meta-data | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/vendor-data (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Read 17 bytes from /var/lib/cloud/seed/nocloud-net/vendor-data | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/network-config (quiet=False) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Read 281 bytes from /var/lib/cloud/seed/nocloud-net/network-config | |
2023-02-06 19:20:23,218 - DataSourceNoCloud.py[DEBUG]: Using seeded data from /var/lib/cloud/seed/nocloud-net | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Attempting to load yaml from string of length 76 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,218 - util.py[DEBUG]: Attempting to load yaml from string of length 281 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,223 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) | |
2023-02-06 19:20:23,223 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:20:23,223 - subp.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2023-02-06 19:20:23,228 - subp.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2023-02-06 19:20:23,232 - subp.py[DEBUG]: Running command ['blkid', '-tLABEL=CIDATA', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2023-02-06 19:20:23,236 - subp.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2023-02-06 19:20:23,240 - subp.py[DEBUG]: Running command ['blkid', '-tLABEL_FATBOOT=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) | |
2023-02-06 19:20:23,244 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-lxd - wb: [644] 4 bytes | |
2023-02-06 19:20:23,244 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-lxd' | |
2023-02-06 19:20:23,245 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmp1to529qk) - w: [600] 5060 bytes/chars | |
2023-02-06 19:20:23,245 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpthxv48hr) - w: [644] 1626 bytes/chars | |
2023-02-06 19:20:23,245 - handlers.py[DEBUG]: finish: init-local/search-NoCloud: SUCCESS: found local data from DataSourceNoCloud | |
2023-02-06 19:20:23,245 - stages.py[INFO]: Loaded datasource DataSourceNoCloud - DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:20:23,245 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,246 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:20:23,246 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,251 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:20:23,251 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:20:23,251 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2023-02-06 19:20:23,254 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/[redacted]' | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/[redacted]/datasource (quiet=False) | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/datasource - wb: [644] 88 bytes | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 88 bytes | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2023-02-06 19:20:23,255 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 23 bytes | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 23 bytes | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 5528 bytes | |
2023-02-06 19:20:23,255 - main.py[DEBUG]: [local] init will now be targeting instance id: [redacted]. new=True | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,255 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:20:23,256 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,261 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:20:23,261 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:20:23,261 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,263 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,263 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:20:23,263 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,264 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,264 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:20:23,264 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:23,264 - cc_set_hostname.py[DEBUG]: Setting the hostname to [redacted] ([redacted]) | |
2023-02-06 19:20:23,265 - subp.py[DEBUG]: Running command ['hostnamectl', 'set-hostname', '[redacted]'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,292 - __init__.py[DEBUG]: Non-persistently setting the system hostname to [redacted] | |
2023-02-06 19:20:23,293 - subp.py[DEBUG]: Running command ['hostname', '[redacted]'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,295 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmpgkljn5y2) - w: [644] 77 bytes/chars | |
2023-02-06 19:20:23,295 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:20:23,295 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:20:23,295 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:20:23,295 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:20:23,296 - __init__.py[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False) | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type | |
2023-02-06 19:20:23,296 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False) | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Read 26 bytes from /sys/class/net/eth0/uevent | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Reading from /sys/class/net/eth0/type (quiet=False) | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/type | |
2023-02-06 19:20:23,297 - networking.py[DEBUG]: net: all expected physical devices present | |
2023-02-06 19:20:23,297 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}}} | |
2023-02-06 19:20:23,297 - __init__.py[DEBUG]: no interfaces to rename | |
2023-02-06 19:20:23,297 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 2, 'ethernets': {'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}}} | |
2023-02-06 19:20:23,297 - util.py[DEBUG]: Writing to /run/cloud-init/sem/apply_network_config.once - wb: [644] 24 bytes | |
2023-02-06 19:20:23,297 - network_state.py[DEBUG]: NetworkState Version2: missing "macaddress" info in config entry: eth0: {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]} | |
2023-02-06 19:20:23,297 - network_state.py[DEBUG]: v2(ethernets) -> v1(physical): | |
{'type': 'physical', 'name': 'eth0', 'mac_address': None, 'subnets': [{'type': 'static', 'address': '188.[redacted]/32', 'gateway': '188.[redacted]', 'dns_nameservers': ['8.8.8.8', '8.8.4.4'], 'routes': [{'gateway': '169.254.0.1', 'network': '0.0.0.0', 'prefix': 0, 'netmask': '0.0.0.0'}]}]} | |
2023-02-06 19:20:23,300 - network_state.py[DEBUG]: v2_common: handling config: | |
{'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}} | |
2023-02-06 19:20:23,300 - __init__.py[DEBUG]: Selected renderer 'sysconfig' from priority list: None | |
2023-02-06 19:20:23,300 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-eth0 - wb: [644] 263 bytes | |
2023-02-06 19:20:23,302 - util.py[DEBUG]: Reading from /etc/resolv.conf (quiet=False) | |
2023-02-06 19:20:23,302 - util.py[DEBUG]: Read 0 bytes from /etc/resolv.conf | |
2023-02-06 19:20:23,302 - util.py[DEBUG]: Writing to /etc/resolv.conf - wb: [644] 109 bytes | |
2023-02-06 19:20:23,303 - util.py[DEBUG]: Writing to /etc/NetworkManager/conf.d/99-cloud-init.conf - wb: [644] 89 bytes | |
2023-02-06 19:20:23,303 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [644] 0 bytes | |
2023-02-06 19:20:23,303 - sysconfig.py[DEBUG]: Enabled ifcfg-rh NetworkManager plugins | |
2023-02-06 19:20:23,303 - util.py[DEBUG]: Reading from /etc/sysconfig/network (quiet=True) | |
2023-02-06 19:20:23,303 - util.py[DEBUG]: Read 47 bytes from /etc/sysconfig/network | |
2023-02-06 19:20:23,303 - util.py[DEBUG]: Writing to /etc/sysconfig/network - wb: [644] 119 bytes | |
2023-02-06 19:20:23,304 - __init__.py[DEBUG]: Not bringing up newly configured network interfaces | |
2023-02-06 19:20:23,304 - main.py[DEBUG]: [local] Exiting. datasource DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] not in local mode. | |
2023-02-06 19:20:23,304 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpf8cstof3) - w: [644] 540 bytes/chars | |
2023-02-06 19:20:23,304 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:20:23,304 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:20:23,304 - util.py[DEBUG]: cloud-init mode 'init' took 0.111 seconds (0.11) | |
2023-02-06 19:20:23,304 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources | |
2023-02-06 19:20:23,553 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'init' at Mon, 06 Feb 2023 19:20:23 +0000. Up 1.14 seconds. | |
2023-02-06 19:20:23,553 - main.py[DEBUG]: No kernel command line url found. | |
2023-02-06 19:20:23,553 - main.py[DEBUG]: Closing stdin. | |
2023-02-06 19:20:23,553 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2023-02-06 19:20:23,557 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) | |
2023-02-06 19:20:23,557 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:20:23,558 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,560 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,562 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2023-02-06 19:20:23,564 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. | |
2023-02-06 19:20:23,564 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. | |
2023-02-06 19:20:23,564 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] | |
2023-02-06 19:20:23,564 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2023-02-06 19:20:23,564 - util.py[DEBUG]: Read 5528 bytes from /var/lib/cloud/instance/obj.pkl | |
2023-02-06 19:20:23,568 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) | |
2023-02-06 19:20:23,568 - util.py[DEBUG]: Read 23 bytes from /run/cloud-init/.instance-id | |
2023-02-06 19:20:23,568 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:20:23,568 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:20:23,568 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,568 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:20:23,568 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,574 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:20:23,574 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:20:23,574 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,576 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,576 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:20:23,576 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,576 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,576 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:20:23,577 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2023-02-06 19:20:23,577 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/[redacted]' | |
2023-02-06 19:20:23,577 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/[redacted]/datasource (quiet=False) | |
2023-02-06 19:20:23,577 - util.py[DEBUG]: Read 88 bytes from /var/lib/cloud/instances/[redacted]/datasource | |
2023-02-06 19:20:23,577 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/datasource - wb: [644] 88 bytes | |
2023-02-06 19:20:23,579 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 88 bytes | |
2023-02-06 19:20:23,580 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2023-02-06 19:20:23,580 - util.py[DEBUG]: Read 23 bytes from /var/lib/cloud/data/instance-id | |
2023-02-06 19:20:23,580 - stages.py[DEBUG]: previous iid found to be [redacted] | |
2023-02-06 19:20:23,580 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 23 bytes | |
2023-02-06 19:20:23,580 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 23 bytes | |
2023-02-06 19:20:23,580 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 23 bytes | |
2023-02-06 19:20:23,581 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 5543 bytes | |
2023-02-06 19:20:23,582 - main.py[DEBUG]: [net] init will now be targeting instance id: [redacted]. new=False | |
2023-02-06 19:20:23,582 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,582 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:20:23,582 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,587 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:20:23,587 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:20:23,587 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,590 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,590 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:20:23,590 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,590 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,590 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:20:23,591 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:20:23,591 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:20:23,591 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:20:23,591 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:20:23,591 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:20:23,591 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy | |
2023-02-06 19:20:23,591 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed | |
2023-02-06 19:20:23,591 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}}} | |
2023-02-06 19:20:23,592 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:23,592 - __init__.py[DEBUG]: no interfaces to rename | |
2023-02-06 19:20:23,592 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource | |
2023-02-06 19:20:23,592 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource | |
2023-02-06 19:20:23,592 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/user-data.txt - wb: [600] 985 bytes | |
2023-02-06 19:20:23,592 - util.py[DEBUG]: Attempting to load yaml from string of length 985 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,594 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/user-data.txt.i - wb: [600] 1290 bytes | |
2023-02-06 19:20:23,595 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data.txt - wb: [600] 17 bytes | |
2023-02-06 19:20:23,595 - util.py[DEBUG]: Attempting to load yaml from string of length 17 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,595 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data.txt.i - wb: [600] 322 bytes | |
2023-02-06 19:20:23,595 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data2.txt - wb: [600] 0 bytes | |
2023-02-06 19:20:23,596 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data2.txt.i - wb: [600] 308 bytes | |
2023-02-06 19:20:23,596 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2023-02-06 19:20:23,596 - util.py[DEBUG]: Read 77 bytes from /var/lib/cloud/data/set-hostname | |
2023-02-06 19:20:23,596 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2023-02-06 19:20:23,596 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/consume_data - wb: [644] 23 bytes | |
2023-02-06 19:20:23,597 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/consume_data'>) | |
2023-02-06 19:20:23,597 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/cloud-config', 'text/cloud-config-jsonp'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2023-02-06 19:20:23,597 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,597 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2023-02-06 19:20:23,598 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,598 - util.py[DEBUG]: Attempting to load yaml from string of length 985 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,599 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2023-02-06 19:20:23,599 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/cloud-config.txt - wb: [600] 1024 bytes | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,601 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data | |
2023-02-06 19:20:23,601 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data | |
2023-02-06 19:20:23,601 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:20:23,601 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:20:23,601 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: vendordata will be consumed. disabled_handlers=None | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/cloud-config', 'text/cloud-config-jsonp'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2023-02-06 19:20:23,603 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,603 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - util.py[DEBUG]: Attempting to load yaml from string of length 17 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,604 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-cloud-config.txt - wb: [600] 53 bytes | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance | |
2023-02-06 19:20:23,604 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data | |
2023-02-06 19:20:23,604 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 | |
2023-02-06 19:20:23,604 - stages.py[DEBUG]: no vendordata2 from datasource | |
2023-02-06 19:20:23,604 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 | |
2023-02-06 19:20:23,604 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,604 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:20:23,605 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,610 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:20:23,610 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:20:23,610 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,612 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:20:23,612 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:20:23,612 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,613 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,613 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:20:23,613 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:20:23,613 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:20:23,613 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,615 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:20:23,615 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:20:23,615 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,615 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,615 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,617 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,617 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,618 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,618 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,619 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,619 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,620 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,620 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,621 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,621 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,622 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,622 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,623 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,623 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,623 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,623 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,624 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,624 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,625 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:23,626 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:23,639 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:23,639 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:20:23,639 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:20:23,639 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,641 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:20:23,641 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:20:23,641 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:20:23,643 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource | |
2023-02-06 19:20:23,643 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 8563 bytes | |
2023-02-06 19:20:23,643 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource | |
2023-02-06 19:20:23,643 - main.py[DEBUG]: no di_report found in config. | |
2023-02-06 19:20:23,645 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:23,645 - stages.py[INFO]: Skipping modules 'ca-certs' because they are not verified on distro 'almalinux'. To run anyway, add them to 'unverified_modules' in config. | |
2023-02-06 19:20:23,645 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_migrator.py'>) with frequency always | |
2023-02-06 19:20:23,645 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always | |
2023-02-06 19:20:23,645 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7f16caef11c0>) | |
2023-02-06 19:20:23,645 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names | |
2023-02-06 19:20:23,645 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully | |
2023-02-06 19:20:23,645 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_seed_random.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,646 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance | |
2023-02-06 19:20:23,646 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_seed_random - wb: [644] 24 bytes | |
2023-02-06 19:20:23,646 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_seed_random'>) | |
2023-02-06 19:20:23,646 - cc_seed_random.py[DEBUG]: no command provided | |
2023-02-06 19:20:23,646 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully | |
2023-02-06 19:20:23,646 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_bootcmd.py'>) with frequency always | |
2023-02-06 19:20:23,646 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always | |
2023-02-06 19:20:23,646 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7f16caef11c0>) | |
2023-02-06 19:20:23,646 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration | |
2023-02-06 19:20:23,646 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully | |
2023-02-06 19:20:23,646 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_write_files.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,646 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance | |
2023-02-06 19:20:23,646 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_write_files - wb: [644] 24 bytes | |
2023-02-06 19:20:23,646 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_write_files'>) | |
2023-02-06 19:20:23,647 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:23,647 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration | |
2023-02-06 19:20:23,647 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully | |
2023-02-06 19:20:23,647 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_growpart.py'>) with frequency always | |
2023-02-06 19:20:23,647 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always | |
2023-02-06 19:20:23,647 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f16caf1d970>) | |
2023-02-06 19:20:23,647 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} | |
2023-02-06 19:20:23,647 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,648 - subp.py[DEBUG]: Running command ['gpart', 'help'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2023-02-06 19:20:23,650 - cc_growpart.py[DEBUG]: growpart unable to find resizer for 'auto': No resizers available | |
2023-02-06 19:20:23,650 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully | |
2023-02-06 19:20:23,650 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_resizefs.py'>) with frequency always | |
2023-02-06 19:20:23,650 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always | |
2023-02-06 19:20:23,650 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f16caf1d250>) | |
2023-02-06 19:20:23,651 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:23,651 - util.py[DEBUG]: Reading from /proc/136/mountinfo (quiet=False) | |
2023-02-06 19:20:23,651 - util.py[DEBUG]: Read 4930 bytes from /proc/136/mountinfo | |
2023-02-06 19:20:23,651 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/md123 mnt_point=/ path=/ | |
2023-02-06 19:20:23,651 - cc_resizefs.py[DEBUG]: Device '/dev/md123' did not exist in container. cannot resize: dev=/dev/md123 mnt_point=/ path=/ | |
2023-02-06 19:20:23,651 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully | |
2023-02-06 19:20:23,651 - stages.py[DEBUG]: Running module disk_setup (<module 'cloudinit.config.cc_disk_setup' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_disk_setup.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,651 - handlers.py[DEBUG]: start: init-network/config-disk_setup: running config-disk_setup with frequency once-per-instance | |
2023-02-06 19:20:23,651 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_disk_setup - wb: [644] 24 bytes | |
2023-02-06 19:20:23,651 - helpers.py[DEBUG]: Running config-disk_setup using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_disk_setup'>) | |
2023-02-06 19:20:23,651 - handlers.py[DEBUG]: finish: init-network/config-disk_setup: SUCCESS: config-disk_setup ran successfully | |
2023-02-06 19:20:23,651 - stages.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,652 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance | |
2023-02-06 19:20:23,652 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_mounts - wb: [644] 24 bytes | |
2023-02-06 19:20:23,652 - helpers.py[DEBUG]: Running config-mounts using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_mounts'>) | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: mounts configuration is [] | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: changed default device swap => None | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: no need to setup swap | |
2023-02-06 19:20:23,652 - cc_mounts.py[DEBUG]: No modifications to fstab needed | |
2023-02-06 19:20:23,652 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully | |
2023-02-06 19:20:23,652 - stages.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,652 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance | |
2023-02-06 19:20:23,652 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_set_hostname - wb: [644] 22 bytes | |
2023-02-06 19:20:23,652 - helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_set_hostname'>) | |
2023-02-06 19:20:23,652 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2023-02-06 19:20:23,653 - util.py[DEBUG]: Read 77 bytes from /var/lib/cloud/data/set-hostname | |
2023-02-06 19:20:23,653 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2023-02-06 19:20:23,653 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully | |
2023-02-06 19:20:23,653 - stages.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always | |
2023-02-06 19:20:23,653 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always | |
2023-02-06 19:20:23,653 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7f16caeb1af0>) | |
2023-02-06 19:20:23,653 - cc_update_hostname.py[DEBUG]: Updating hostname to [redacted] ([redacted]) | |
2023-02-06 19:20:23,653 - subp.py[DEBUG]: Running command ['hostname'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,655 - __init__.py[DEBUG]: Attempting to update hostname to [redacted] in 1 files | |
2023-02-06 19:20:23,655 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 22 bytes | |
2023-02-06 19:20:23,655 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully | |
2023-02-06 19:20:23,655 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_update_etc_hosts.py'>) with frequency always | |
2023-02-06 19:20:23,655 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always | |
2023-02-06 19:20:23,655 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7f16caeb2d90>) | |
2023-02-06 19:20:23,655 - cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts | |
2023-02-06 19:20:23,655 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully | |
2023-02-06 19:20:23,655 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_rsyslog.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,656 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance | |
2023-02-06 19:20:23,656 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_rsyslog - wb: [644] 23 bytes | |
2023-02-06 19:20:23,656 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_rsyslog'>) | |
2023-02-06 19:20:23,656 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration | |
2023-02-06 19:20:23,656 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully | |
2023-02-06 19:20:23,656 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,656 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance | |
2023-02-06 19:20:23,656 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_users_groups - wb: [644] 23 bytes | |
2023-02-06 19:20:23,656 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_users_groups'>) | |
2023-02-06 19:20:23,657 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2023-02-06 19:20:23,657 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:20:23,658 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2023-02-06 19:20:23,658 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2023-02-06 19:20:23,658 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2023-02-06 19:20:23,658 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:20:23,659 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2023-02-06 19:20:23,659 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2023-02-06 19:20:23,659 - subp.py[DEBUG]: Running command ['groupadd', 'sudo'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,717 - __init__.py[INFO]: Created new group sudo | |
2023-02-06 19:20:23,717 - __init__.py[DEBUG]: created group 'sudo' for user 'luken' | |
2023-02-06 19:20:23,717 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2023-02-06 19:20:23,717 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:20:23,718 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2023-02-06 19:20:23,718 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2023-02-06 19:20:23,718 - subp.py[DEBUG]: Running command ['groupadd', 'luken'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,766 - __init__.py[INFO]: Created new group luken | |
2023-02-06 19:20:23,767 - __init__.py[DEBUG]: created group 'luken' for user 'luken' | |
2023-02-06 19:20:23,767 - __init__.py[DEBUG]: Adding user luken | |
2023-02-06 19:20:23,767 - subp.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'luken', '--groups', 'sudo', '--gid', 'luken', '--shell', '/bin/bash', '-m'] | |
2023-02-06 19:20:23,887 - subp.py[DEBUG]: Running command ['passwd', '-l', 'luken'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:23,922 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) | |
2023-02-06 19:20:23,923 - util.py[DEBUG]: Read 4328 bytes from /etc/sudoers | |
2023-02-06 19:20:23,923 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 131 bytes | |
2023-02-06 19:20:23,923 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2023-02-06 19:20:23,923 - util.py[DEBUG]: Read 3667 bytes from /etc/ssh/sshd_config | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Changing the ownership of /home/luken/.ssh to 1000:1001 | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Writing to /home/luken/.ssh/authorized_keys - wb: [600] 0 bytes | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Changing the ownership of /home/luken/.ssh/authorized_keys to 1000:1001 | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Reading from /home/luken/.ssh/authorized_keys (quiet=False) | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Read 0 bytes from /home/luken/.ssh/authorized_keys | |
2023-02-06 19:20:23,924 - util.py[DEBUG]: Writing to /home/luken/.ssh/authorized_keys - wb: [600] 732 bytes | |
2023-02-06 19:20:23,925 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully | |
2023-02-06 19:20:23,925 - stages.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance | |
2023-02-06 19:20:23,925 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance | |
2023-02-06 19:20:23,925 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_ssh - wb: [644] 24 bytes | |
2023-02-06 19:20:23,925 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_ssh'>) | |
2023-02-06 19:20:23,925 - subp.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) | |
2023-02-06 19:20:24,195 - subp.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) | |
2023-02-06 19:20:24,200 - subp.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) | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Read 581 bytes from /etc/ssh/ssh_host_rsa_key.pub | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Read 189 bytes from /etc/ssh/ssh_host_ecdsa_key.pub | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Read 109 bytes from /etc/ssh/ssh_host_ed25519_key.pub | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2023-02-06 19:20:24,206 - util.py[DEBUG]: Read 3667 bytes from /etc/ssh/sshd_config | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False) | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Read 0 bytes from /root/.ssh/authorized_keys | |
2023-02-06 19:20:24,207 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes | |
2023-02-06 19:20:24,207 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully | |
2023-02-06 19:20:24,207 - main.py[DEBUG]: Ran 14 modules with 0 failures | |
2023-02-06 19:20:24,208 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp70xuki38) - w: [644] 567 bytes/chars | |
2023-02-06 19:20:24,208 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:20:24,208 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:20:24,208 - util.py[DEBUG]: cloud-init mode 'init' took 0.677 seconds (0.68) | |
2023-02-06 19:20:24,208 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources | |
2023-02-06 19:20:29,544 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'modules:config' at Mon, 06 Feb 2023 19:20:29 +0000. Up 7.11 seconds. | |
2023-02-06 19:20:29,548 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,548 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,549 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,549 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,550 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,551 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,551 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,551 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,552 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,552 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,553 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,553 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,554 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,554 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,556 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,556 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,557 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,557 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,558 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,558 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,570 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:29,570 - stages.py[INFO]: Skipping modules 'ssh-import-id,spacewalk' because they are not verified on distro 'almalinux'. To run anyway, add them to 'unverified_modules' in config. | |
2023-02-06 19:20:29,570 - stages.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,571 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_locale - wb: [644] 24 bytes | |
2023-02-06 19:20:29,571 - helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_locale'>) | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Reading from /etc/locale.conf (quiet=False) | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Read 16 bytes from /etc/locale.conf | |
2023-02-06 19:20:29,571 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:29,571 - cc_locale.py[DEBUG]: Setting locale to en_US.utf8 | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Reading from /etc/locale.conf (quiet=False) | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Read 16 bytes from /etc/locale.conf | |
2023-02-06 19:20:29,571 - util.py[DEBUG]: Writing to /etc/locale.conf - wb: [644] 16 bytes | |
2023-02-06 19:20:29,572 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully | |
2023-02-06 19:20:29,572 - stages.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,572 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance | |
2023-02-06 19:20:29,572 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_set_passwords - wb: [644] 23 bytes | |
2023-02-06 19:20:29,572 - helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_set_passwords'>) | |
2023-02-06 19:20:29,572 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2023-02-06 19:20:29,572 - util.py[DEBUG]: Read 3667 bytes from /etc/ssh/sshd_config | |
2023-02-06 19:20:29,572 - ssh_util.py[DEBUG]: line 131: option PasswordAuthentication added with no | |
2023-02-06 19:20:29,572 - util.py[DEBUG]: Writing to /etc/ssh/sshd_config - wb: [600] 3693 bytes | |
2023-02-06 19:20:29,572 - subp.py[DEBUG]: Running command ['systemctl', 'restart', 'sshd'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:29,591 - cc_set_passwords.py[DEBUG]: Restarted the SSH daemon. | |
2023-02-06 19:20:29,592 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully | |
2023-02-06 19:20:29,592 - stages.py[DEBUG]: Running module yum-add-repo (<module 'cloudinit.config.cc_yum_add_repo' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_yum_add_repo.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,592 - handlers.py[DEBUG]: start: modules-config/config-yum-add-repo: running config-yum-add-repo with frequency once-per-instance | |
2023-02-06 19:20:29,592 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_yum_add_repo - wb: [644] 24 bytes | |
2023-02-06 19:20:29,592 - helpers.py[DEBUG]: Running config-yum-add-repo using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_yum_add_repo'>) | |
2023-02-06 19:20:29,592 - cc_yum_add_repo.py[DEBUG]: Skipping module named yum-add-repo, no 'yum_repos' configuration found | |
2023-02-06 19:20:29,592 - handlers.py[DEBUG]: finish: modules-config/config-yum-add-repo: SUCCESS: config-yum-add-repo ran successfully | |
2023-02-06 19:20:29,592 - stages.py[DEBUG]: Running module ntp (<module 'cloudinit.config.cc_ntp' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ntp.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,593 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance | |
2023-02-06 19:20:29,593 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_ntp - wb: [644] 24 bytes | |
2023-02-06 19:20:29,593 - helpers.py[DEBUG]: Running config-ntp using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_ntp'>) | |
2023-02-06 19:20:29,593 - cc_ntp.py[DEBUG]: Skipping module named ntp, not present or disabled by cfg | |
2023-02-06 19:20:29,593 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully | |
2023-02-06 19:20:29,593 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_timezone.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,593 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance | |
2023-02-06 19:20:29,593 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_timezone - wb: [644] 24 bytes | |
2023-02-06 19:20:29,593 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_timezone'>) | |
2023-02-06 19:20:29,593 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified | |
2023-02-06 19:20:29,593 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully | |
2023-02-06 19:20:29,593 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_disable_ec2_metadata.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,593 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency once-per-instance | |
2023-02-06 19:20:29,593 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_disable_ec2_metadata - wb: [644] 24 bytes | |
2023-02-06 19:20:29,593 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_disable_ec2_metadata'>) | |
2023-02-06 19:20:29,594 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled | |
2023-02-06 19:20:29,594 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully | |
2023-02-06 19:20:29,594 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,594 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance | |
2023-02-06 19:20:29,594 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_runcmd - wb: [644] 24 bytes | |
2023-02-06 19:20:29,594 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_runcmd'>) | |
2023-02-06 19:20:29,594 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration | |
2023-02-06 19:20:29,595 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully | |
2023-02-06 19:20:29,595 - main.py[DEBUG]: Ran 7 modules with 0 failures | |
2023-02-06 19:20:29,595 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp4xzfo2ty) - w: [644] 595 bytes/chars | |
2023-02-06 19:20:29,595 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:20:29,595 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:20:29,595 - util.py[DEBUG]: cloud-init mode 'modules' took 0.088 seconds (0.08) | |
2023-02-06 19:20:29,595 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config | |
2023-02-06 19:20:29,800 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'modules:final' at Mon, 06 Feb 2023 19:20:29 +0000. Up 7.36 seconds. | |
2023-02-06 19:20:29,803 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,804 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,804 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,805 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,806 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,806 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,807 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,807 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,807 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,807 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,808 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,808 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,809 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,809 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,810 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,810 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,812 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,812 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,813 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:20:29,813 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:20:29,826 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:20:29,827 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance | |
2023-02-06 19:20:29,827 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance | |
2023-02-06 19:20:29,827 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_package_update_upgrade_install - wb: [644] 24 bytes | |
2023-02-06 19:20:29,827 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_package_update_upgrade_install'>) | |
2023-02-06 19:20:29,827 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/update_sources - wb: [644] 23 bytes | |
2023-02-06 19:20:29,827 - helpers.py[DEBUG]: Running update-sources using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/update_sources'>) | |
2023-02-06 19:20:29,827 - rhel.py[DEBUG]: Using DNF for package management | |
2023-02-06 19:20:29,827 - subp.py[DEBUG]: Running command ['dnf', '-y', 'makecache'] with allowed return codes [0] (shell=False, capture=False) | |
2023-02-06 19:20:29,968 - util.py[WARNING]: Package update failed | |
2023-02-06 19:20:29,968 - util.py[DEBUG]: Package update failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py", line 87, in handle | |
cloud.distro.update_package_sources() | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 196, in update_package_sources | |
self._runner.run( | |
File "/usr/lib/python3.9/site-packages/cloudinit/helpers.py", line 185, in run | |
results = functor(*args) | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 193, in package_command | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3.9/site-packages/cloudinit/subp.py", line 332, in subp | |
raise ProcessExecutionError( | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['dnf', '-y', 'makecache'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2023-02-06 19:20:29,969 - rhel.py[DEBUG]: Using DNF for package management | |
2023-02-06 19:20:29,969 - subp.py[DEBUG]: Running command ['dnf', '-y', 'upgrade'] with allowed return codes [0] (shell=False, capture=False) | |
2023-02-06 19:20:30,402 - util.py[WARNING]: Package upgrade failed | |
2023-02-06 19:20:30,402 - util.py[DEBUG]: Package upgrade failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py", line 94, in handle | |
cloud.distro.package_command("upgrade") | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 193, in package_command | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3.9/site-packages/cloudinit/subp.py", line 332, in subp | |
raise ProcessExecutionError( | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['dnf', '-y', 'upgrade'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2023-02-06 19:20:30,402 - rhel.py[DEBUG]: Using DNF for package management | |
2023-02-06 19:20:30,402 - subp.py[DEBUG]: Running command ['dnf', '-y', 'install', 'openssh-server'] with allowed return codes [0] (shell=False, capture=False) | |
2023-02-06 19:20:30,708 - util.py[WARNING]: Failed to install packages: ['openssh-server'] | |
2023-02-06 19:20:30,709 - util.py[DEBUG]: Failed to install packages: ['openssh-server'] | |
Traceback (most recent call last): | |
File "/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py", line 101, in handle | |
cloud.distro.install_packages(pkglist) | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 66, in install_packages | |
self.package_command("install", pkgs=pkglist) | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 193, in package_command | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3.9/site-packages/cloudinit/subp.py", line 332, in subp | |
raise ProcessExecutionError( | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['dnf', '-y', 'install', 'openssh-server'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2023-02-06 19:20:30,709 - cc_package_update_upgrade_install.py[WARNING]: 3 failed with exceptions, re-raising the last one | |
2023-02-06 19:20:30,709 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: FAIL: running config-package-update-upgrade-install with frequency once-per-instance | |
2023-02-06 19:20:30,709 - util.py[WARNING]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) failed | |
2023-02-06 19:20:30,709 - util.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3.9/site-packages/cloudinit/stages.py", line 1087, in _run_modules | |
ran, _r = cc.run( | |
File "/usr/lib/python3.9/site-packages/cloudinit/cloud.py", line 55, in run | |
return self._runners.run(name, functor, args, freq, clear_on_fail) | |
File "/usr/lib/python3.9/site-packages/cloudinit/helpers.py", line 185, in run | |
results = functor(*args) | |
File "/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py", line 127, in handle | |
raise errors[-1] | |
File "/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py", line 101, in handle | |
cloud.distro.install_packages(pkglist) | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 66, in install_packages | |
self.package_command("install", pkgs=pkglist) | |
File "/usr/lib/python3.9/site-packages/cloudinit/distros/rhel.py", line 193, in package_command | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3.9/site-packages/cloudinit/subp.py", line 332, in subp | |
raise ProcessExecutionError( | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['dnf', '-y', 'install', 'openssh-server'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2023-02-06 19:20:30,710 - stages.py[DEBUG]: Running module write-files-deferred (<module 'cloudinit.config.cc_write_files_deferred' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_write_files_deferred.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,710 - handlers.py[DEBUG]: start: modules-final/config-write-files-deferred: running config-write-files-deferred with frequency once-per-instance | |
2023-02-06 19:20:30,710 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_write_files_deferred - wb: [644] 23 bytes | |
2023-02-06 19:20:30,710 - helpers.py[DEBUG]: Running config-write-files-deferred using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_write_files_deferred'>) | |
2023-02-06 19:20:30,710 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:30,710 - cc_write_files_deferred.py[DEBUG]: Skipping module named write-files-deferred, no deferred file defined in configuration | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: finish: modules-final/config-write-files-deferred: SUCCESS: config-write-files-deferred ran successfully | |
2023-02-06 19:20:30,711 - stages.py[DEBUG]: Running module puppet (<module 'cloudinit.config.cc_puppet' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_puppet.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance | |
2023-02-06 19:20:30,711 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_puppet - wb: [644] 23 bytes | |
2023-02-06 19:20:30,711 - helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_puppet'>) | |
2023-02-06 19:20:30,711 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet ran successfully | |
2023-02-06 19:20:30,711 - stages.py[DEBUG]: Running module chef (<module 'cloudinit.config.cc_chef' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_chef.py'>) with frequency always | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency always | |
2023-02-06 19:20:30,711 - helpers.py[DEBUG]: Running config-chef using lock (<cloudinit.helpers.DummyLock object at 0x7f9d50e440d0>) | |
2023-02-06 19:20:30,711 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully | |
2023-02-06 19:20:30,711 - stages.py[DEBUG]: Running module mcollective (<module 'cloudinit.config.cc_mcollective' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_mcollective.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,711 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance | |
2023-02-06 19:20:30,711 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_mcollective - wb: [644] 23 bytes | |
2023-02-06 19:20:30,711 - helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_mcollective'>) | |
2023-02-06 19:20:30,711 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective ran successfully | |
2023-02-06 19:20:30,712 - stages.py[DEBUG]: Running module salt-minion (<module 'cloudinit.config.cc_salt_minion' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_salt_minion.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance | |
2023-02-06 19:20:30,712 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_salt_minion - wb: [644] 24 bytes | |
2023-02-06 19:20:30,712 - helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_salt_minion'>) | |
2023-02-06 19:20:30,712 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion ran successfully | |
2023-02-06 19:20:30,712 - stages.py[DEBUG]: Running module reset_rmc (<module 'cloudinit.config.cc_reset_rmc' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_reset_rmc.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance | |
2023-02-06 19:20:30,712 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_reset_rmc - wb: [644] 24 bytes | |
2023-02-06 19:20:30,712 - helpers.py[DEBUG]: Running config-reset_rmc using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_reset_rmc'>) | |
2023-02-06 19:20:30,712 - cc_reset_rmc.py[DEBUG]: module disabled, RSCT_PATH not present | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc ran successfully | |
2023-02-06 19:20:30,712 - stages.py[DEBUG]: Running module refresh_rmc_and_interface (<module 'cloudinit.config.cc_refresh_rmc_and_interface' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_refresh_rmc_and_interface.py'>) with frequency always | |
2023-02-06 19:20:30,712 - handlers.py[DEBUG]: start: modules-final/config-refresh_rmc_and_interface: running config-refresh_rmc_and_interface with frequency always | |
2023-02-06 19:20:30,713 - helpers.py[DEBUG]: Running config-refresh_rmc_and_interface using lock (<cloudinit.helpers.DummyLock object at 0x7f9d50e44670>) | |
2023-02-06 19:20:30,713 - cc_refresh_rmc_and_interface.py[DEBUG]: No 'rmcctrl' in path, disabled | |
2023-02-06 19:20:30,713 - handlers.py[DEBUG]: finish: modules-final/config-refresh_rmc_and_interface: SUCCESS: config-refresh_rmc_and_interface ran successfully | |
2023-02-06 19:20:30,713 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_rightscale_userdata.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,713 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance | |
2023-02-06 19:20:30,713 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_rightscale_userdata - wb: [644] 23 bytes | |
2023-02-06 19:20:30,713 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_rightscale_userdata'>) | |
2023-02-06 19:20:30,713 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata | |
2023-02-06 19:20:30,713 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully | |
2023-02-06 19:20:30,713 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,713 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance | |
2023-02-06 19:20:30,713 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_scripts_vendor - wb: [644] 24 bytes | |
2023-02-06 19:20:30,713 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_scripts_vendor'>) | |
2023-02-06 19:20:30,713 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully | |
2023-02-06 19:20:30,713 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once | |
2023-02-06 19:20:30,714 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 24 bytes | |
2023-02-06 19:20:30,714 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>) | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully | |
2023-02-06 19:20:30,714 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always | |
2023-02-06 19:20:30,714 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7f9d50d0f5e0>) | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully | |
2023-02-06 19:20:30,714 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance | |
2023-02-06 19:20:30,714 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_scripts_per_instance - wb: [644] 23 bytes | |
2023-02-06 19:20:30,714 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_scripts_per_instance'>) | |
2023-02-06 19:20:30,714 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully | |
2023-02-06 19:20:30,714 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,715 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance | |
2023-02-06 19:20:30,715 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_scripts_user - wb: [644] 22 bytes | |
2023-02-06 19:20:30,715 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_scripts_user'>) | |
2023-02-06 19:20:30,715 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully | |
2023-02-06 19:20:30,715 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,715 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance | |
2023-02-06 19:20:30,715 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_ssh_authkey_fingerprints - wb: [644] 23 bytes | |
2023-02-06 19:20:30,715 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_ssh_authkey_fingerprints'>) | |
2023-02-06 19:20:30,715 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2023-02-06 19:20:30,715 - util.py[DEBUG]: Read 3693 bytes from /etc/ssh/sshd_config | |
2023-02-06 19:20:30,716 - util.py[DEBUG]: Reading from /home/luken/.ssh/authorized_keys (quiet=False) | |
2023-02-06 19:20:30,716 - util.py[DEBUG]: Read 732 bytes from /home/luken/.ssh/authorized_keys | |
2023-02-06 19:20:30,717 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints ran successfully | |
2023-02-06 19:20:30,717 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,717 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance | |
2023-02-06 19:20:30,717 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_keys_to_console - wb: [644] 23 bytes | |
2023-02-06 19:20:30,717 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_keys_to_console'>) | |
2023-02-06 19:20:30,717 - subp.py[DEBUG]: Running command ['/usr/libexec/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:20:30,780 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully | |
2023-02-06 19:20:30,781 - stages.py[DEBUG]: Running module install-hotplug (<module 'cloudinit.config.cc_install_hotplug' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_install_hotplug.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,781 - handlers.py[DEBUG]: start: modules-final/config-install-hotplug: running config-install-hotplug with frequency once-per-instance | |
2023-02-06 19:20:30,781 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_install_hotplug - wb: [644] 24 bytes | |
2023-02-06 19:20:30,781 - helpers.py[DEBUG]: Running config-install-hotplug using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_install_hotplug'>) | |
2023-02-06 19:20:30,781 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:20:30,782 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:20:30,782 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=hotplug | |
2023-02-06 19:20:30,782 - cc_install_hotplug.py[DEBUG]: Skipping hotplug install, not enabled | |
2023-02-06 19:20:30,782 - handlers.py[DEBUG]: finish: modules-final/config-install-hotplug: SUCCESS: config-install-hotplug ran successfully | |
2023-02-06 19:20:30,782 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_phone_home.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,782 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance | |
2023-02-06 19:20:30,782 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_phone_home - wb: [644] 24 bytes | |
2023-02-06 19:20:30,782 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_phone_home'>) | |
2023-02-06 19:20:30,782 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found | |
2023-02-06 19:20:30,782 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully | |
2023-02-06 19:20:30,782 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_final_message.py'>) with frequency always | |
2023-02-06 19:20:30,782 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always | |
2023-02-06 19:20:30,782 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7f9d50d0fca0>) | |
2023-02-06 19:20:30,782 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:20:30,783 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:20:30,786 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma finished at Mon, 06 Feb 2023 19:20:30 +0000. Datasource DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net]. Up 8.38 seconds | |
2023-02-06 19:20:30,786 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 60 bytes | |
2023-02-06 19:20:30,786 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully | |
2023-02-06 19:20:30,786 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_power_state_change.py'>) with frequency once-per-instance | |
2023-02-06 19:20:30,786 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance | |
2023-02-06 19:20:30,786 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/sem/config_power_state_change - wb: [644] 24 bytes | |
2023-02-06 19:20:30,786 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/[redacted]/sem/config_power_state_change'>) | |
2023-02-06 19:20:30,786 - cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing | |
2023-02-06 19:20:30,787 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully | |
2023-02-06 19:20:30,787 - main.py[DEBUG]: Ran 20 modules with 1 failures | |
2023-02-06 19:20:30,787 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp9gs_a32b) - w: [644] 841 bytes/chars | |
2023-02-06 19:20:30,787 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmp_vij3pc4) - w: [644] 335 bytes/chars | |
2023-02-06 19:20:30,787 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' | |
2023-02-06 19:20:30,787 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:20:30,788 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:20:30,788 - util.py[DEBUG]: cloud-init mode 'modules' took 1.025 seconds (1.03) | |
2023-02-06 19:20:30,788 - handlers.py[DEBUG]: finish: modules-final: FAIL: running modules for final | |
2023-02-06 19:24:57,750 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'init-local' at Mon, 06 Feb 2023 19:24:57 +0000. Up 0.64 seconds. | |
2023-02-06 19:24:57,750 - main.py[DEBUG]: No kernel command line url found. | |
2023-02-06 19:24:57,750 - main.py[DEBUG]: Closing stdin. | |
2023-02-06 19:24:57,751 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2023-02-06 19:24:57,751 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished | |
2023-02-06 19:24:57,751 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net | |
2023-02-06 19:24:57,751 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] | |
2023-02-06 19:24:57,751 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2023-02-06 19:24:57,752 - util.py[DEBUG]: Read 8563 bytes from /var/lib/cloud/instance/obj.pkl | |
2023-02-06 19:24:57,754 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) | |
2023-02-06 19:24:57,754 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False) | |
2023-02-06 19:24:57,754 - util.py[DEBUG]: Read 76 bytes from /var/lib/cloud/seed/nocloud-net/meta-data | |
2023-02-06 19:24:57,754 - util.py[DEBUG]: Attempting to load yaml from string of length 76 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,754 - stages.py[DEBUG]: restored from checked cache: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:24:57,755 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: restored from checked cache: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:24:57,755 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:24:57,755 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:24:57,755 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,760 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:24:57,760 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:24:57,760 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,764 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:24:57,764 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:24:57,764 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,764 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,764 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:24:57,765 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:57,765 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:57,765 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,766 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:57,766 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:57,766 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,768 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) | |
2023-02-06 19:24:57,769 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:24:57,770 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-lxd - wb: [644] 4 bytes | |
2023-02-06 19:24:57,770 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-lxd' | |
2023-02-06 19:24:57,770 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmptzf3mg9g) - w: [600] 5060 bytes/chars | |
2023-02-06 19:24:57,771 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpkni7w4ws) - w: [644] 1626 bytes/chars | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/[redacted]' | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/[redacted]/datasource (quiet=False) | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Read 88 bytes from /var/lib/cloud/instances/[redacted]/datasource | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/datasource - wb: [644] 88 bytes | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 88 bytes | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Read 23 bytes from /var/lib/cloud/data/instance-id | |
2023-02-06 19:24:57,771 - stages.py[DEBUG]: previous iid found to be [redacted] | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:57,771 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:57,772 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:57,772 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 8565 bytes | |
2023-02-06 19:24:57,772 - main.py[DEBUG]: [local] init will now be targeting instance id: [redacted]. new=False | |
2023-02-06 19:24:57,772 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:24:57,772 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:24:57,772 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,778 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:24:57,778 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:24:57,778 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,780 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:24:57,780 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:24:57,780 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,781 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,781 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:24:57,781 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:57,781 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:57,781 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,783 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:57,783 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:57,783 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:57,783 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Read 77 bytes from /var/lib/cloud/data/set-hostname | |
2023-02-06 19:24:57,784 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:24:57,784 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed | |
2023-02-06 19:24:57,785 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}}} | |
2023-02-06 19:24:57,785 - __init__.py[DEBUG]: no interfaces to rename | |
2023-02-06 19:24:57,785 - main.py[DEBUG]: [local] Exiting. datasource DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] not in local mode. | |
2023-02-06 19:24:57,785 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpls6_awiu) - w: [644] 541 bytes/chars | |
2023-02-06 19:24:57,785 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:24:57,786 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:24:57,786 - util.py[DEBUG]: cloud-init mode 'init' took 0.058 seconds (0.05) | |
2023-02-06 19:24:57,786 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources | |
2023-02-06 19:24:58,061 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'init' at Mon, 06 Feb 2023 19:24:58 +0000. Up 0.95 seconds. | |
2023-02-06 19:24:58,061 - main.py[DEBUG]: No kernel command line url found. | |
2023-02-06 19:24:58,061 - main.py[DEBUG]: Closing stdin. | |
2023-02-06 19:24:58,061 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2023-02-06 19:24:58,064 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) | |
2023-02-06 19:24:58,065 - util.py[DEBUG]: Read 543 bytes from /etc/os-release | |
2023-02-06 19:24:58,065 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:24:58,068 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:24:58,070 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2023-02-06 19:24:58,072 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. | |
2023-02-06 19:24:58,072 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. | |
2023-02-06 19:24:58,072 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] | |
2023-02-06 19:24:58,072 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2023-02-06 19:24:58,072 - util.py[DEBUG]: Read 8565 bytes from /var/lib/cloud/instance/obj.pkl | |
2023-02-06 19:24:58,076 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) | |
2023-02-06 19:24:58,076 - util.py[DEBUG]: Read 23 bytes from /run/cloud-init/.instance-id | |
2023-02-06 19:24:58,076 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:24:58,076 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net] | |
2023-02-06 19:24:58,076 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,076 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:24:58,076 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,082 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:24:58,082 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:24:58,082 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,084 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,084 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:24:58,084 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,085 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,085 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:24:58,085 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,085 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:58,085 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/[redacted]' | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/[redacted]/datasource (quiet=False) | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Read 88 bytes from /var/lib/cloud/instances/[redacted]/datasource | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/datasource - wb: [644] 88 bytes | |
2023-02-06 19:24:58,087 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 88 bytes | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Read 23 bytes from /var/lib/cloud/data/instance-id | |
2023-02-06 19:24:58,088 - stages.py[DEBUG]: previous iid found to be [redacted] | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 23 bytes | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 8565 bytes | |
2023-02-06 19:24:58,088 - main.py[DEBUG]: [net] init will now be targeting instance id: [redacted]. new=False | |
2023-02-06 19:24:58,088 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,089 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:24:58,089 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,094 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:24:58,094 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:24:58,094 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,096 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,096 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:24:58,096 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,097 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,097 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:24:58,097 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,097 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:58,097 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2023-02-06 19:24:58,099 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'eth0': {'dhcp4': False, 'dhcp6': False, 'gateway4': '188.[redacted]', 'addresses': ['188.[redacted]/32'], 'nameservers': {'addresses': ['8.8.8.8', '8.8.4.4'], 'search': []}, 'routes': [{'to': '0.0.0.0/0', 'via': '169.254.0.1', 'on-link': True}]}}} | |
2023-02-06 19:24:58,100 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:24:58,101 - __init__.py[DEBUG]: no interfaces to rename | |
2023-02-06 19:24:58,101 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource | |
2023-02-06 19:24:58,101 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource | |
2023-02-06 19:24:58,101 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/user-data.txt - wb: [600] 985 bytes | |
2023-02-06 19:24:58,101 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/user-data.txt.i - wb: [600] 1290 bytes | |
2023-02-06 19:24:58,101 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data.txt - wb: [600] 17 bytes | |
2023-02-06 19:24:58,102 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data.txt.i - wb: [600] 322 bytes | |
2023-02-06 19:24:58,102 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data2.txt - wb: [600] 0 bytes | |
2023-02-06 19:24:58,102 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-data2.txt.i - wb: [600] 308 bytes | |
2023-02-06 19:24:58,103 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2023-02-06 19:24:58,103 - util.py[DEBUG]: Read 77 bytes from /var/lib/cloud/data/set-hostname | |
2023-02-06 19:24:58,103 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2023-02-06 19:24:58,103 - helpers.py[DEBUG]: consume_data already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,103 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2023-02-06 19:24:58,103 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] | |
2023-02-06 19:24:58,103 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency always | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2023-02-06 19:24:58,104 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always | |
2023-02-06 19:24:58,104 - util.py[DEBUG]: Attempting to load yaml from string of length 985 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,105 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2023-02-06 19:24:58,105 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always | |
2023-02-06 19:24:58,107 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/cloud-config.txt - wb: [600] 1024 bytes | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,107 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency always | |
2023-02-06 19:24:58,107 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data | |
2023-02-06 19:24:58,107 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data | |
2023-02-06 19:24:58,107 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,107 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:58,107 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: vendordata will be consumed. disabled_handlers=None | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2023-02-06 19:24:58,109 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] | |
2023-02-06 19:24:58,109 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always | |
2023-02-06 19:24:58,109 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,109 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,109 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always | |
2023-02-06 19:24:58,110 - util.py[DEBUG]: Attempting to load yaml from string of length 17 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,110 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always | |
2023-02-06 19:24:58,110 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/[redacted]/vendor-cloud-config.txt - wb: [600] 53 bytes | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always | |
2023-02-06 19:24:58,110 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency always | |
2023-02-06 19:24:58,110 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data | |
2023-02-06 19:24:58,110 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 | |
2023-02-06 19:24:58,110 - stages.py[DEBUG]: no vendordata2 from datasource | |
2023-02-06 19:24:58,110 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 | |
2023-02-06 19:24:58,111 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,111 - util.py[DEBUG]: Read 2681 bytes from /etc/cloud/cloud.cfg | |
2023-02-06 19:24:58,111 - util.py[DEBUG]: Attempting to load yaml from string of length 2681 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,116 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2023-02-06 19:24:58,116 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2023-02-06 19:24:58,116 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Read 40 bytes from /run/cloud-init/cloud.cfg | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Attempting to load yaml from string of length 40 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:58,119 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,121 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,121 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:58,121 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,121 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,121 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,123 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,123 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,125 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,125 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,125 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,125 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,126 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,126 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,127 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,127 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,128 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,128 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,129 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,129 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,130 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,130 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,130 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,131 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,132 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:24:58,132 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:24:58,145 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:24:58,145 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,145 - util.py[DEBUG]: Read 1024 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2023-02-06 19:24:58,145 - util.py[DEBUG]: Attempting to load yaml from string of length 1024 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,147 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False) | |
2023-02-06 19:24:58,147 - util.py[DEBUG]: Read 53 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt | |
2023-02-06 19:24:58,147 - util.py[DEBUG]: Attempting to load yaml from string of length 53 with allowed root types (<class 'dict'>,) | |
2023-02-06 19:24:58,149 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource | |
2023-02-06 19:24:58,149 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 9527 bytes | |
2023-02-06 19:24:58,149 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource | |
2023-02-06 19:24:58,149 - main.py[DEBUG]: no di_report found in config. | |
2023-02-06 19:24:58,151 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:24:58,151 - stages.py[INFO]: Skipping modules 'ca-certs' because they are not verified on distro 'almalinux'. To run anyway, add them to 'unverified_modules' in config. | |
2023-02-06 19:24:58,151 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_migrator.py'>) with frequency always | |
2023-02-06 19:24:58,151 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always | |
2023-02-06 19:24:58,152 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7f888d884040>) | |
2023-02-06 19:24:58,152 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names | |
2023-02-06 19:24:58,152 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully | |
2023-02-06 19:24:58,152 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_seed_random.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,152 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance | |
2023-02-06 19:24:58,152 - helpers.py[DEBUG]: config-seed_random already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,152 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random previously ran | |
2023-02-06 19:24:58,152 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_bootcmd.py'>) with frequency always | |
2023-02-06 19:24:58,152 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always | |
2023-02-06 19:24:58,152 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7f888d884190>) | |
2023-02-06 19:24:58,152 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration | |
2023-02-06 19:24:58,152 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully | |
2023-02-06 19:24:58,152 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_write_files.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,153 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance | |
2023-02-06 19:24:58,153 - helpers.py[DEBUG]: config-write-files already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,153 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files previously ran | |
2023-02-06 19:24:58,153 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_growpart.py'>) with frequency always | |
2023-02-06 19:24:58,153 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always | |
2023-02-06 19:24:58,153 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f888d884190>) | |
2023-02-06 19:24:58,153 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} | |
2023-02-06 19:24:58,153 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:24:58,154 - subp.py[DEBUG]: Running command ['gpart', 'help'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2023-02-06 19:24:58,156 - cc_growpart.py[DEBUG]: growpart unable to find resizer for 'auto': No resizers available | |
2023-02-06 19:24:58,156 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully | |
2023-02-06 19:24:58,156 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_resizefs.py'>) with frequency always | |
2023-02-06 19:24:58,156 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always | |
2023-02-06 19:24:58,156 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f888d884040>) | |
2023-02-06 19:24:58,157 - schema.py[DEBUG]: Ignoring schema validation. jsonschema is not present | |
2023-02-06 19:24:58,157 - util.py[DEBUG]: Reading from /proc/123/mountinfo (quiet=False) | |
2023-02-06 19:24:58,157 - util.py[DEBUG]: Read 4930 bytes from /proc/123/mountinfo | |
2023-02-06 19:24:58,157 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/md123 mnt_point=/ path=/ | |
2023-02-06 19:24:58,157 - cc_resizefs.py[DEBUG]: Device '/dev/md123' did not exist in container. cannot resize: dev=/dev/md123 mnt_point=/ path=/ | |
2023-02-06 19:24:58,157 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully | |
2023-02-06 19:24:58,157 - stages.py[DEBUG]: Running module disk_setup (<module 'cloudinit.config.cc_disk_setup' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_disk_setup.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,157 - handlers.py[DEBUG]: start: init-network/config-disk_setup: running config-disk_setup with frequency once-per-instance | |
2023-02-06 19:24:58,157 - helpers.py[DEBUG]: config-disk_setup already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,157 - handlers.py[DEBUG]: finish: init-network/config-disk_setup: SUCCESS: config-disk_setup previously ran | |
2023-02-06 19:24:58,157 - stages.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,157 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance | |
2023-02-06 19:24:58,157 - helpers.py[DEBUG]: config-mounts already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,157 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts previously ran | |
2023-02-06 19:24:58,157 - stages.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,158 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance | |
2023-02-06 19:24:58,158 - helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,158 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran | |
2023-02-06 19:24:58,158 - stages.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always | |
2023-02-06 19:24:58,158 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always | |
2023-02-06 19:24:58,158 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7f888d8b2b50>) | |
2023-02-06 19:24:58,158 - cc_update_hostname.py[DEBUG]: Updating hostname to [redacted] ([redacted]) | |
2023-02-06 19:24:58,158 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) | |
2023-02-06 19:24:58,158 - util.py[DEBUG]: Read 22 bytes from /var/lib/cloud/data/previous-hostname | |
2023-02-06 19:24:58,158 - subp.py[DEBUG]: Running command ['hostname'] with allowed return codes [0] (shell=False, capture=True) | |
2023-02-06 19:24:58,160 - __init__.py[INFO]: /var/lib/cloud/data/previous-hostname differs from /etc/hostname, assuming user maintained hostname. | |
2023-02-06 19:24:58,160 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully | |
2023-02-06 19:24:58,160 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_update_etc_hosts.py'>) with frequency always | |
2023-02-06 19:24:58,160 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always | |
2023-02-06 19:24:58,160 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7f888d884190>) | |
2023-02-06 19:24:58,160 - cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts | |
2023-02-06 19:24:58,160 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully | |
2023-02-06 19:24:58,160 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_rsyslog.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance | |
2023-02-06 19:24:58,161 - helpers.py[DEBUG]: config-rsyslog already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog previously ran | |
2023-02-06 19:24:58,161 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance | |
2023-02-06 19:24:58,161 - helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran | |
2023-02-06 19:24:58,161 - stages.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance | |
2023-02-06 19:24:58,161 - helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance) | |
2023-02-06 19:24:58,161 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran | |
2023-02-06 19:24:58,161 - main.py[DEBUG]: Ran 14 modules with 0 failures | |
2023-02-06 19:24:58,162 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpdujv9ja7) - w: [644] 569 bytes/chars | |
2023-02-06 19:24:58,162 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:24:58,162 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:24:58,162 - util.py[DEBUG]: cloud-init mode 'init' took 0.125 seconds (0.12) | |
2023-02-06 19:24:58,162 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources | |
2023-02-06 19:25:04,084 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'modules:config' at Mon, 06 Feb 2023 19:25:04 +0000. Up 6.96 seconds. | |
2023-02-06 19:25:04,089 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,089 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,090 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,090 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,091 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,091 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,092 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,092 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,092 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,092 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,093 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,093 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,094 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,094 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,095 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,095 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,096 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,096 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,098 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,098 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,109 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:25:04,110 - stages.py[INFO]: Skipping modules 'ssh-import-id,spacewalk' because they are not verified on distro 'almalinux'. To run anyway, add them to 'unverified_modules' in config. | |
2023-02-06 19:25:04,110 - stages.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,110 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance | |
2023-02-06 19:25:04,110 - helpers.py[DEBUG]: config-locale already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,110 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale previously ran | |
2023-02-06 19:25:04,110 - stages.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,110 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance | |
2023-02-06 19:25:04,110 - helpers.py[DEBUG]: config-set-passwords already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,110 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords previously ran | |
2023-02-06 19:25:04,110 - stages.py[DEBUG]: Running module yum-add-repo (<module 'cloudinit.config.cc_yum_add_repo' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_yum_add_repo.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: start: modules-config/config-yum-add-repo: running config-yum-add-repo with frequency once-per-instance | |
2023-02-06 19:25:04,111 - helpers.py[DEBUG]: config-yum-add-repo already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: finish: modules-config/config-yum-add-repo: SUCCESS: config-yum-add-repo previously ran | |
2023-02-06 19:25:04,111 - stages.py[DEBUG]: Running module ntp (<module 'cloudinit.config.cc_ntp' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ntp.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance | |
2023-02-06 19:25:04,111 - helpers.py[DEBUG]: config-ntp already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp previously ran | |
2023-02-06 19:25:04,111 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_timezone.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance | |
2023-02-06 19:25:04,111 - helpers.py[DEBUG]: config-timezone already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone previously ran | |
2023-02-06 19:25:04,111 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_disable_ec2_metadata.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency once-per-instance | |
2023-02-06 19:25:04,111 - helpers.py[DEBUG]: config-disable-ec2-metadata already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata previously ran | |
2023-02-06 19:25:04,111 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance | |
2023-02-06 19:25:04,111 - helpers.py[DEBUG]: config-runcmd already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,111 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd previously ran | |
2023-02-06 19:25:04,111 - main.py[DEBUG]: Ran 7 modules with 0 failures | |
2023-02-06 19:25:04,112 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpk3rgy2mh) - w: [644] 595 bytes/chars | |
2023-02-06 19:25:04,112 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:25:04,112 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:25:04,112 - util.py[DEBUG]: cloud-init mode 'modules' took 0.064 seconds (0.06) | |
2023-02-06 19:25:04,112 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config | |
2023-02-06 19:25:04,309 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma running 'modules:final' at Mon, 06 Feb 2023 19:25:04 +0000. Up 7.18 seconds. | |
2023-02-06 19:25:04,312 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,312 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,313 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,313 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,315 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,315 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,315 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,315 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,316 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,316 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,317 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,317 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,318 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,318 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,319 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,319 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,320 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,320 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,321 - util.py[DEBUG]: Reading from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json (quiet=False) | |
2023-02-06 19:25:04,321 - util.py[DEBUG]: Read 28978 bytes from /usr/lib/python3.9/site-packages/cloudinit/config/cloud-init-schema.json | |
2023-02-06 19:25:04,335 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.almalinux.Distro'> | |
2023-02-06 19:25:04,335 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,335 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance | |
2023-02-06 19:25:04,335 - helpers.py[DEBUG]: config-package-update-upgrade-install already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,335 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install previously ran | |
2023-02-06 19:25:04,335 - stages.py[DEBUG]: Running module write-files-deferred (<module 'cloudinit.config.cc_write_files_deferred' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_write_files_deferred.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,335 - handlers.py[DEBUG]: start: modules-final/config-write-files-deferred: running config-write-files-deferred with frequency once-per-instance | |
2023-02-06 19:25:04,335 - helpers.py[DEBUG]: config-write-files-deferred already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: finish: modules-final/config-write-files-deferred: SUCCESS: config-write-files-deferred previously ran | |
2023-02-06 19:25:04,336 - stages.py[DEBUG]: Running module puppet (<module 'cloudinit.config.cc_puppet' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_puppet.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance | |
2023-02-06 19:25:04,336 - helpers.py[DEBUG]: config-puppet already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet previously ran | |
2023-02-06 19:25:04,336 - stages.py[DEBUG]: Running module chef (<module 'cloudinit.config.cc_chef' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_chef.py'>) with frequency always | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency always | |
2023-02-06 19:25:04,336 - helpers.py[DEBUG]: Running config-chef using lock (<cloudinit.helpers.DummyLock object at 0x7fbf95d7cee0>) | |
2023-02-06 19:25:04,336 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully | |
2023-02-06 19:25:04,336 - stages.py[DEBUG]: Running module mcollective (<module 'cloudinit.config.cc_mcollective' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_mcollective.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance | |
2023-02-06 19:25:04,336 - helpers.py[DEBUG]: config-mcollective already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective previously ran | |
2023-02-06 19:25:04,336 - stages.py[DEBUG]: Running module salt-minion (<module 'cloudinit.config.cc_salt_minion' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_salt_minion.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance | |
2023-02-06 19:25:04,336 - helpers.py[DEBUG]: config-salt-minion already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,336 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion previously ran | |
2023-02-06 19:25:04,336 - stages.py[DEBUG]: Running module reset_rmc (<module 'cloudinit.config.cc_reset_rmc' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_reset_rmc.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance | |
2023-02-06 19:25:04,337 - helpers.py[DEBUG]: config-reset_rmc already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc previously ran | |
2023-02-06 19:25:04,337 - stages.py[DEBUG]: Running module refresh_rmc_and_interface (<module 'cloudinit.config.cc_refresh_rmc_and_interface' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_refresh_rmc_and_interface.py'>) with frequency always | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: start: modules-final/config-refresh_rmc_and_interface: running config-refresh_rmc_and_interface with frequency always | |
2023-02-06 19:25:04,337 - helpers.py[DEBUG]: Running config-refresh_rmc_and_interface using lock (<cloudinit.helpers.DummyLock object at 0x7fbf95d7cee0>) | |
2023-02-06 19:25:04,337 - cc_refresh_rmc_and_interface.py[DEBUG]: No 'rmcctrl' in path, disabled | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: finish: modules-final/config-refresh_rmc_and_interface: SUCCESS: config-refresh_rmc_and_interface ran successfully | |
2023-02-06 19:25:04,337 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_rightscale_userdata.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance | |
2023-02-06 19:25:04,337 - helpers.py[DEBUG]: config-rightscale_userdata already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata previously ran | |
2023-02-06 19:25:04,337 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance | |
2023-02-06 19:25:04,337 - helpers.py[DEBUG]: config-scripts-vendor already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,337 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously ran | |
2023-02-06 19:25:04,337 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once | |
2023-02-06 19:25:04,338 - helpers.py[DEBUG]: config-scripts-per-once already ran (freq=once) | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once previously ran | |
2023-02-06 19:25:04,338 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always | |
2023-02-06 19:25:04,338 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7fbf95d32730>) | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully | |
2023-02-06 19:25:04,338 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance | |
2023-02-06 19:25:04,338 - helpers.py[DEBUG]: config-scripts-per-instance already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance previously ran | |
2023-02-06 19:25:04,338 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance | |
2023-02-06 19:25:04,338 - helpers.py[DEBUG]: config-scripts-user already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user previously ran | |
2023-02-06 19:25:04,338 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,338 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance | |
2023-02-06 19:25:04,339 - helpers.py[DEBUG]: config-ssh-authkey-fingerprints already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints previously ran | |
2023-02-06 19:25:04,339 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance | |
2023-02-06 19:25:04,339 - helpers.py[DEBUG]: config-keys-to-console already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console previously ran | |
2023-02-06 19:25:04,339 - stages.py[DEBUG]: Running module install-hotplug (<module 'cloudinit.config.cc_install_hotplug' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_install_hotplug.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: start: modules-final/config-install-hotplug: running config-install-hotplug with frequency once-per-instance | |
2023-02-06 19:25:04,339 - helpers.py[DEBUG]: config-install-hotplug already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: finish: modules-final/config-install-hotplug: SUCCESS: config-install-hotplug previously ran | |
2023-02-06 19:25:04,339 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_phone_home.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance | |
2023-02-06 19:25:04,339 - helpers.py[DEBUG]: config-phone-home already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home previously ran | |
2023-02-06 19:25:04,339 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_final_message.py'>) with frequency always | |
2023-02-06 19:25:04,339 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always | |
2023-02-06 19:25:04,339 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7fbf95d32790>) | |
2023-02-06 19:25:04,339 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:25:04,340 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:25:04,342 - util.py[DEBUG]: Cloud-init v. 22.1-5.el9.alma finished at Mon, 06 Feb 2023 19:25:04 +0000. Datasource DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud-net][dsmode=net]. Up 7.25 seconds | |
2023-02-06 19:25:04,342 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 60 bytes | |
2023-02-06 19:25:04,342 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully | |
2023-02-06 19:25:04,342 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/lib/python3.9/site-packages/cloudinit/config/cc_power_state_change.py'>) with frequency once-per-instance | |
2023-02-06 19:25:04,342 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance | |
2023-02-06 19:25:04,342 - helpers.py[DEBUG]: config-power-state-change already ran (freq=once-per-instance) | |
2023-02-06 19:25:04,342 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change previously ran | |
2023-02-06 19:25:04,342 - main.py[DEBUG]: Ran 20 modules with 0 failures | |
2023-02-06 19:25:04,343 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp499v2ufb) - w: [644] 623 bytes/chars | |
2023-02-06 19:25:04,343 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmpm9mj6fhx) - w: [644] 119 bytes/chars | |
2023-02-06 19:25:04,343 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' | |
2023-02-06 19:25:04,343 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2023-02-06 19:25:04,343 - util.py[DEBUG]: Read 10 bytes from /proc/uptime | |
2023-02-06 19:25:04,343 - util.py[DEBUG]: cloud-init mode 'modules' took 0.070 seconds (0.07) | |
2023-02-06 19:25:04,343 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment