root@juju-47a237-0:~# cat /var/log/cloud-init.log 2024-04-19 10:20:29,006 - util.py[DEBUG]: Cloud-init v. 23.4.4-0ubuntu0~22.04.1 running 'init-local' at Fri, 19 Apr 2024 10:20:28 +0000. Up 0.57 seconds. 2024-04-19 10:20:29,006 - main.py[INFO]: PID [1] started cloud-init. 2024-04-19 10:20:29,006 - main.py[DEBUG]: No kernel command line url found. 2024-04-19 10:20:29,006 - main.py[DEBUG]: Closing stdin. 2024-04-19 10:20:29,009 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes 2024-04-19 10:20:29,009 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 2024-04-19 10:20:29,009 - util.py[DEBUG]: Writing to /var/lib/cloud/data/python-version - wb: [644] 4 bytes 2024-04-19 10:20:29,010 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished 2024-04-19 10:20:29,010 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] 2024-04-19 10:20:29,010 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2024-04-19 10:20:29,010 - stages.py[DEBUG]: no cache found 2024-04-19 10:20:29,010 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found 2024-04-19 10:20:29,010 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2024-04-19 10:20:29,012 - stages.py[DEBUG]: Using distro class 2024-04-19 10:20:29,012 - __init__.py[DEBUG]: Looking for data source in: ['LXD', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] 2024-04-19 10:20:29,014 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceLXD'] 2024-04-19 10:20:29,014 - handlers.py[DEBUG]: start: init-local/search-LXD: searching for local data from DataSourceLXD 2024-04-19 10:20:29,014 - __init__.py[DEBUG]: Seeing if we can get any data from 2024-04-19 10:20:29,014 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: boot-new-instance 2024-04-19 10:20:29,015 - __init__.py[DEBUG]: Detected platform: DataSourceLXD. Checking for active instance data 2024-04-19 10:20:29,018 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/meta-data 2024-04-19 10:20:29,020 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/config 2024-04-19 10:20:29,022 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/config/user.juju-controller-uuid 2024-04-19 10:20:29,024 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/config/user.juju-is-controller 2024-04-19 10:20:29,025 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/config/user.juju-model-uuid 2024-04-19 10:20:29,027 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/config/user.user-data 2024-04-19 10:20:29,045 - DataSourceLXD.py[DEBUG]: [GET] [HTTP:200] http://lxd/1.0/devices 2024-04-19 10:20:29,045 - util.py[DEBUG]: Crawl of metadata service took 0.030 seconds 2024-04-19 10:20:29,045 - util.py[DEBUG]: Attempting to load yaml from string of length 94 with allowed root types (,) 2024-04-19 10:20:29,054 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) 2024-04-19 10:20:29,055 - util.py[DEBUG]: Read 386 bytes from /etc/os-release 2024-04-19 10:20:29,055 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-lxd - wb: [644] 4 bytes 2024-04-19 10:20:29,056 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-lxd' 2024-04-19 10:20:29,056 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpuze6vgcl) - w: [600] 13096 bytes/chars 2024-04-19 10:20:29,056 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpb9tt6i5s) - w: [644] 2169 bytes/chars 2024-04-19 10:20:29,056 - handlers.py[DEBUG]: finish: init-local/search-LXD: SUCCESS: found local data from DataSourceLXD 2024-04-19 10:20:29,056 - stages.py[INFO]: Loaded datasource DataSourceLXD - DataSourceLXD 2024-04-19 10:20:29,057 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-04-19 10:20:29,057 - util.py[DEBUG]: Read 3756 bytes from /etc/cloud/cloud.cfg 2024-04-19 10:20:29,057 - util.py[DEBUG]: Attempting to load yaml from string of length 3756 with allowed root types (,) 2024-04-19 10:20:29,067 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-04-19 10:20:29,068 - util.py[DEBUG]: Read 328 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-04-19 10:20:29,068 - util.py[DEBUG]: Attempting to load yaml from string of length 328 with allowed root types (,) 2024-04-19 10:20:29,069 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-04-19 10:20:29,070 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-04-19 10:20:29,070 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2024-04-19 10:20:29,073 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-04-19 10:20:29,073 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-04-19 10:20:29,074 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-04-19 10:20:29,074 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-04-19 10:20:29,074 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-04-19 10:20:29,075 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2024-04-19 10:20:29,075 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266' 2024-04-19 10:20:29,076 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/datasource (quiet=False) 2024-04-19 10:20:29,076 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/datasource - wb: [644] 29 bytes 2024-04-19 10:20:29,076 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 29 bytes 2024-04-19 10:20:29,076 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2024-04-19 10:20:29,076 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID 2024-04-19 10:20:29,077 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes 2024-04-19 10:20:29,077 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes 2024-04-19 10:20:29,077 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes 2024-04-19 10:20:29,078 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 15692 bytes 2024-04-19 10:20:29,078 - main.py[DEBUG]: [local] init will now be targeting instance id: 8d3a9fbd-98c5-4d91-b0e8-0131c4343266. new=True 2024-04-19 10:20:29,078 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-04-19 10:20:29,078 - util.py[DEBUG]: Read 3756 bytes from /etc/cloud/cloud.cfg 2024-04-19 10:20:29,078 - util.py[DEBUG]: Attempting to load yaml from string of length 3756 with allowed root types (,) 2024-04-19 10:20:29,088 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-04-19 10:20:29,088 - util.py[DEBUG]: Read 328 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-04-19 10:20:29,088 - util.py[DEBUG]: Attempting to load yaml from string of length 328 with allowed root types (,) 2024-04-19 10:20:29,090 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-04-19 10:20:29,090 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-04-19 10:20:29,090 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2024-04-19 10:20:29,092 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-04-19 10:20:29,092 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-04-19 10:20:29,092 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-04-19 10:20:29,092 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-04-19 10:20:29,092 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-04-19 10:20:29,094 - stages.py[DEBUG]: Using distro class 2024-04-19 10:20:29,094 - cc_set_hostname.py[DEBUG]: Setting the hostname to juju-47a237-0 (juju-47a237-0) 2024-04-19 10:20:29,094 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2024-04-19 10:20:29,094 - util.py[DEBUG]: Read 14 bytes from /etc/hostname 2024-04-19 10:20:29,095 - util.py[DEBUG]: Writing to /etc/hostname - wb: [644] 14 bytes 2024-04-19 10:20:29,095 - __init__.py[DEBUG]: Non-persistently setting the system hostname to juju-47a237-0 2024-04-19 10:20:29,095 - subp.py[DEBUG]: Running command ['hostname', 'juju-47a237-0'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:29,096 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmpjdwprytp) - w: [644] 59 bytes/chars 2024-04-19 10:20:29,097 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:29,097 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:29,097 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:29,097 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/eth0/name_assign_type (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/name_assign_type 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/carrier 2024-04-19 10:20:29,098 - DataSourceLXD.py[DEBUG]: LXD datasource generating network from discovered active device: eth0 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:29,098 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:29,098 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:29,099 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/instance/network-config.json (via temporary file /var/lib/cloud/instance/tmpjecpl5s0) - w: [600] 162 bytes/chars 2024-04-19 10:20:29,099 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/network-config.json' => '/var/lib/cloud/instance/network-config.json' 2024-04-19 10:20:29,099 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-network-config-v1.json (quiet=False) 2024-04-19 10:20:29,099 - util.py[DEBUG]: Read 20018 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-network-config-v1.json 2024-04-19 10:20:29,100 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 26 bytes from /sys/class/net/eth0/uevent 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:29,101 - __init__.py[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/eth0/type (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/type 2024-04-19 10:20:29,101 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2024-04-19 10:20:29,101 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2024-04-19 10:20:29,101 - networking.py[DEBUG]: net: all expected physical devices present 2024-04-19 10:20:29,101 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'subnets': [{'type': 'dhcp', 'control': 'auto'}]}]} 2024-04-19 10:20:29,101 - __init__.py[DEBUG]: no interfaces to rename 2024-04-19 10:20:29,101 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'subnets': [{'type': 'dhcp', 'control': 'auto'}]}]} 2024-04-19 10:20:29,102 - util.py[DEBUG]: Writing to /run/cloud-init/sem/apply_network_config.once - wb: [644] 24 bytes 2024-04-19 10:20:29,102 - __init__.py[DEBUG]: Selected renderer 'netplan' from priority list: ['netplan', 'eni', 'sysconfig'] 2024-04-19 10:20:29,104 - subp.py[DEBUG]: Running command ['netplan', 'info'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:29,169 - util.py[DEBUG]: Attempting to load yaml from string of length 311 with allowed root types (,) 2024-04-19 10:20:29,170 - util.py[DEBUG]: Writing to /etc/netplan/50-cloud-init.yaml - wb: [644] 389 bytes 2024-04-19 10:20:29,171 - subp.py[DEBUG]: Running command ['netplan', 'generate'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:29,236 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/eth0'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:29,239 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/lo'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:29,242 - __init__.py[DEBUG]: Not bringing up newly configured network interfaces 2024-04-19 10:20:29,242 - main.py[DEBUG]: [local] Exiting. datasource DataSourceLXD not in local mode. 2024-04-19 10:20:29,243 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp6bag6lx6) - w: [644] 435 bytes/chars 2024-04-19 10:20:29,243 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-04-19 10:20:29,244 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-04-19 10:20:29,244 - util.py[DEBUG]: cloud-init mode 'init' took 0.275 seconds (0.27) 2024-04-19 10:20:29,244 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources 2024-04-19 10:20:30,104 - util.py[DEBUG]: Cloud-init v. 23.4.4-0ubuntu0~22.04.1 running 'init' at Fri, 19 Apr 2024 10:20:30 +0000. Up 1.68 seconds. 2024-04-19 10:20:30,104 - main.py[INFO]: PID [1] started cloud-init. 2024-04-19 10:20:30,104 - main.py[DEBUG]: No kernel command line url found. 2024-04-19 10:20:30,105 - main.py[DEBUG]: Closing stdin. 2024-04-19 10:20:30,105 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes 2024-04-19 10:20:30,105 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 2024-04-19 10:20:30,106 - util.py[DEBUG]: Reading from /etc/os-release (quiet=False) 2024-04-19 10:20:30,106 - util.py[DEBUG]: Read 386 bytes from /etc/os-release 2024-04-19 10:20:30,107 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:30,107 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:30,108 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) 2024-04-19 10:20:30,109 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] 2024-04-19 10:20:30,109 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2024-04-19 10:20:30,109 - util.py[DEBUG]: Read 15692 bytes from /var/lib/cloud/instance/obj.pkl 2024-04-19 10:20:30,110 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) 2024-04-19 10:20:30,110 - util.py[DEBUG]: Read 37 bytes from /run/cloud-init/.instance-id 2024-04-19 10:20:30,110 - stages.py[DEBUG]: restored from cache with run check: DataSourceLXD 2024-04-19 10:20:30,110 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceLXD 2024-04-19 10:20:30,110 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-04-19 10:20:30,111 - util.py[DEBUG]: Read 3756 bytes from /etc/cloud/cloud.cfg 2024-04-19 10:20:30,111 - util.py[DEBUG]: Attempting to load yaml from string of length 3756 with allowed root types (,) 2024-04-19 10:20:30,116 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-04-19 10:20:30,116 - util.py[DEBUG]: Read 328 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-04-19 10:20:30,116 - util.py[DEBUG]: Attempting to load yaml from string of length 328 with allowed root types (,) 2024-04-19 10:20:30,117 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-04-19 10:20:30,117 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-04-19 10:20:30,117 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2024-04-19 10:20:30,118 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-04-19 10:20:30,118 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-04-19 10:20:30,118 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-04-19 10:20:30,118 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-04-19 10:20:30,118 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-04-19 10:20:30,119 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2024-04-19 10:20:30,119 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266' 2024-04-19 10:20:30,119 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/datasource (quiet=False) 2024-04-19 10:20:30,119 - util.py[DEBUG]: Read 29 bytes from /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/datasource 2024-04-19 10:20:30,119 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/datasource - wb: [644] 29 bytes 2024-04-19 10:20:30,119 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 29 bytes 2024-04-19 10:20:30,119 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2024-04-19 10:20:30,119 - util.py[DEBUG]: Read 37 bytes from /var/lib/cloud/data/instance-id 2024-04-19 10:20:30,119 - stages.py[DEBUG]: previous iid found to be 8d3a9fbd-98c5-4d91-b0e8-0131c4343266 2024-04-19 10:20:30,119 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes 2024-04-19 10:20:30,120 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes 2024-04-19 10:20:30,120 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 37 bytes 2024-04-19 10:20:30,120 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 15718 bytes 2024-04-19 10:20:30,120 - main.py[DEBUG]: [net] init will now be targeting instance id: 8d3a9fbd-98c5-4d91-b0e8-0131c4343266. new=False 2024-04-19 10:20:30,120 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-04-19 10:20:30,120 - util.py[DEBUG]: Read 3756 bytes from /etc/cloud/cloud.cfg 2024-04-19 10:20:30,120 - util.py[DEBUG]: Attempting to load yaml from string of length 3756 with allowed root types (,) 2024-04-19 10:20:30,126 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-04-19 10:20:30,126 - util.py[DEBUG]: Read 328 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-04-19 10:20:30,126 - util.py[DEBUG]: Attempting to load yaml from string of length 328 with allowed root types (,) 2024-04-19 10:20:30,126 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-04-19 10:20:30,126 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-04-19 10:20:30,126 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2024-04-19 10:20:30,128 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-04-19 10:20:30,128 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-04-19 10:20:30,128 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-04-19 10:20:30,128 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-04-19 10:20:30,128 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/eth0/name_assign_type (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/name_assign_type 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False) 2024-04-19 10:20:30,129 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/carrier 2024-04-19 10:20:30,129 - DataSourceLXD.py[DEBUG]: LXD datasource generating network from discovered active device: eth0 2024-04-19 10:20:30,129 - stages.py[DEBUG]: Allowed events: {: {}} 2024-04-19 10:20:30,130 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy 2024-04-19 10:20:30,130 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed 2024-04-19 10:20:30,130 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'name': 'eth0', 'subnets': [{'type': 'dhcp', 'control': 'auto'}]}]} 2024-04-19 10:20:30,130 - stages.py[DEBUG]: Using distro class 2024-04-19 10:20:30,130 - __init__.py[DEBUG]: no interfaces to rename 2024-04-19 10:20:30,130 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource 2024-04-19 10:20:30,130 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource 2024-04-19 10:20:30,130 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/user-data.txt - wb: [600] 4314 bytes 2024-04-19 10:20:30,130 - util.py[DEBUG]: Attempting to load yaml from string of length 4314 with allowed root types (,) 2024-04-19 10:20:30,134 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/user-data.txt.i - wb: [600] 4619 bytes 2024-04-19 10:20:30,134 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/vendor-data.txt - wb: [600] 0 bytes 2024-04-19 10:20:30,134 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/vendor-data.txt.i - wb: [600] 308 bytes 2024-04-19 10:20:30,134 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/vendor-data2.txt - wb: [600] 0 bytes 2024-04-19 10:20:30,135 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/vendor-data2.txt.i - wb: [600] 308 bytes 2024-04-19 10:20:30,135 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) 2024-04-19 10:20:30,135 - util.py[DEBUG]: Read 59 bytes from /var/lib/cloud/data/set-hostname 2024-04-19 10:20:30,135 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname 2024-04-19 10:20:30,135 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/consume_data - wb: [644] 24 bytes 2024-04-19 10:20:30,135 - helpers.py[DEBUG]: Running consume_data using lock () 2024-04-19 10:20:30,135 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/cloud-config', 'text/cloud-config-jsonp'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] 2024-04-19 10:20:30,136 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} 2024-04-19 10:20:30,136 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance 2024-04-19 10:20:30,136 - util.py[DEBUG]: Attempting to load yaml from string of length 4314 with allowed root types (,) 2024-04-19 10:20:30,139 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] 2024-04-19 10:20:30,139 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance 2024-04-19 10:20:30,142 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/cloud-config.txt - wb: [600] 4633 bytes 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance 2024-04-19 10:20:30,142 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance 2024-04-19 10:20:30,142 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data 2024-04-19 10:20:30,142 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data 2024-04-19 10:20:30,142 - stages.py[DEBUG]: no vendordata from datasource 2024-04-19 10:20:30,142 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data 2024-04-19 10:20:30,142 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 2024-04-19 10:20:30,142 - stages.py[DEBUG]: no vendordata2 from datasource 2024-04-19 10:20:30,142 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 2024-04-19 10:20:30,142 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-04-19 10:20:30,142 - util.py[DEBUG]: Read 3756 bytes from /etc/cloud/cloud.cfg 2024-04-19 10:20:30,142 - util.py[DEBUG]: Attempting to load yaml from string of length 3756 with allowed root types (,) 2024-04-19 10:20:30,147 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-04-19 10:20:30,147 - util.py[DEBUG]: Read 328 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-04-19 10:20:30,147 - util.py[DEBUG]: Attempting to load yaml from string of length 328 with allowed root types (,) 2024-04-19 10:20:30,148 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-04-19 10:20:30,148 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-04-19 10:20:30,148 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2024-04-19 10:20:30,150 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-04-19 10:20:30,150 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-04-19 10:20:30,150 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-04-19 10:20:30,150 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-04-19 10:20:30,150 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-04-19 10:20:30,150 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-04-19 10:20:30,150 - util.py[DEBUG]: Read 4633 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-04-19 10:20:30,150 - util.py[DEBUG]: Attempting to load yaml from string of length 4633 with allowed root types (,) 2024-04-19 10:20:30,154 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/combined-cloud-config.json (via temporary file /run/cloud-init/tmpnlyivnoy) - w: [600] 9741 bytes/chars 2024-04-19 10:20:30,154 - util.py[DEBUG]: Reading from /run/cloud-init/instance-data-sensitive.json (quiet=False) 2024-04-19 10:20:30,154 - util.py[DEBUG]: Read 13096 bytes from /run/cloud-init/instance-data-sensitive.json 2024-04-19 10:20:30,154 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpjcfty3h7) - w: [600] 15220 bytes/chars 2024-04-19 10:20:30,154 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-04-19 10:20:30,154 - util.py[DEBUG]: Read 4633 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-04-19 10:20:30,157 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,157 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,160 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-04-19 10:20:30,160 - util.py[DEBUG]: Read 4633 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-04-19 10:20:30,160 - util.py[DEBUG]: Attempting to load yaml from string of length 4633 with allowed root types (,) 2024-04-19 10:20:30,164 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource 2024-04-19 10:20:30,164 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 22403 bytes 2024-04-19 10:20:30,164 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource 2024-04-19 10:20:30,164 - main.py[DEBUG]: no di_report found in config. 2024-04-19 10:20:30,165 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,165 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,166 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,166 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,167 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,167 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,168 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,168 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,169 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,169 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,170 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,171 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,172 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,172 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,173 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,173 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,174 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,174 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,175 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,175 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,176 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,176 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,177 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,177 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,178 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,178 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,179 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-04-19 10:20:30,180 - util.py[DEBUG]: Read 144896 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-04-19 10:20:30,181 - stages.py[DEBUG]: Using distro class 2024-04-19 10:20:30,181 - modules.py[INFO]: Skipping modules 'bootcmd,write_files,disk_setup,update_etc_hosts,ca_certs,rsyslog' because no applicable config is provided. 2024-04-19 10:20:30,181 - modules.py[DEBUG]: Running module migrator () with frequency always 2024-04-19 10:20:30,182 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always 2024-04-19 10:20:30,182 - helpers.py[DEBUG]: Running config-migrator using lock () 2024-04-19 10:20:30,182 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names 2024-04-19 10:20:30,182 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully 2024-04-19 10:20:30,182 - modules.py[DEBUG]: Running module seed_random () with frequency once-per-instance 2024-04-19 10:20:30,182 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance 2024-04-19 10:20:30,182 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/config_seed_random - wb: [644] 24 bytes 2024-04-19 10:20:30,182 - helpers.py[DEBUG]: Running config-seed_random using lock () 2024-04-19 10:20:30,182 - cc_seed_random.py[DEBUG]: no command provided 2024-04-19 10:20:30,182 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully 2024-04-19 10:20:30,182 - modules.py[DEBUG]: Running module growpart () with frequency always 2024-04-19 10:20:30,182 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always 2024-04-19 10:20:30,182 - helpers.py[DEBUG]: Running config-growpart using lock () 2024-04-19 10:20:30,182 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} 2024-04-19 10:20:30,182 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:30,184 - util.py[DEBUG]: Reading from /proc/302/mountinfo (quiet=False) 2024-04-19 10:20:30,184 - util.py[DEBUG]: Read 6007 bytes from /proc/302/mountinfo 2024-04-19 10:20:30,184 - util.py[DEBUG]: resize_devices took 0.000 seconds 2024-04-19 10:20:30,184 - cc_growpart.py[DEBUG]: '/' SKIPPED: stat of '/dev/mapper/ubuntu--vg-root' failed: [Errno 2] No such file or directory: '/dev/mapper/ubuntu--vg-root' 2024-04-19 10:20:30,184 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully 2024-04-19 10:20:30,184 - modules.py[DEBUG]: Running module resizefs () with frequency always 2024-04-19 10:20:30,184 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always 2024-04-19 10:20:30,184 - helpers.py[DEBUG]: Running config-resizefs using lock () 2024-04-19 10:20:30,184 - util.py[DEBUG]: Reading from /proc/302/mountinfo (quiet=False) 2024-04-19 10:20:30,184 - util.py[DEBUG]: Read 6007 bytes from /proc/302/mountinfo 2024-04-19 10:20:30,184 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/mapper/ubuntu--vg-root mnt_point=/ path=/ 2024-04-19 10:20:30,184 - cc_resizefs.py[DEBUG]: Device '/dev/mapper/ubuntu--vg-root' did not exist in container. cannot resize: dev=/dev/mapper/ubuntu--vg-root mnt_point=/ path=/ 2024-04-19 10:20:30,184 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully 2024-04-19 10:20:30,184 - modules.py[DEBUG]: Running module mounts () with frequency once-per-instance 2024-04-19 10:20:30,184 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance 2024-04-19 10:20:30,184 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/config_mounts - wb: [644] 24 bytes 2024-04-19 10:20:30,185 - helpers.py[DEBUG]: Running config-mounts using lock () 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: mounts configuration is [] 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: changed default device swap => None 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: no need to setup swap 2024-04-19 10:20:30,185 - cc_mounts.py[DEBUG]: No modifications to fstab needed 2024-04-19 10:20:30,185 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully 2024-04-19 10:20:30,185 - modules.py[DEBUG]: Running module set_hostname () with frequency once-per-instance 2024-04-19 10:20:30,185 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance 2024-04-19 10:20:30,185 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/config_set_hostname - wb: [644] 24 bytes 2024-04-19 10:20:30,185 - helpers.py[DEBUG]: Running config-set_hostname using lock () 2024-04-19 10:20:30,185 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) 2024-04-19 10:20:30,185 - util.py[DEBUG]: Read 59 bytes from /var/lib/cloud/data/set-hostname 2024-04-19 10:20:30,185 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname 2024-04-19 10:20:30,185 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully 2024-04-19 10:20:30,185 - modules.py[DEBUG]: Running module update_hostname () with frequency always 2024-04-19 10:20:30,185 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always 2024-04-19 10:20:30,185 - helpers.py[DEBUG]: Running config-update_hostname using lock () 2024-04-19 10:20:30,185 - cc_update_hostname.py[DEBUG]: Updating hostname to juju-47a237-0 (juju-47a237-0) 2024-04-19 10:20:30,185 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2024-04-19 10:20:30,185 - util.py[DEBUG]: Read 14 bytes from /etc/hostname 2024-04-19 10:20:30,185 - __init__.py[DEBUG]: Attempting to update hostname to juju-47a237-0 in 1 files 2024-04-19 10:20:30,185 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) 2024-04-19 10:20:30,185 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 14 bytes 2024-04-19 10:20:30,186 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully 2024-04-19 10:20:30,186 - modules.py[DEBUG]: Running module users_groups () with frequency once-per-instance 2024-04-19 10:20:30,186 - handlers.py[DEBUG]: start: init-network/config-users_groups: running config-users_groups with frequency once-per-instance 2024-04-19 10:20:30,186 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/config_users_groups - wb: [644] 24 bytes 2024-04-19 10:20:30,186 - helpers.py[DEBUG]: Running config-users_groups using lock () 2024-04-19 10:20:30,186 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) 2024-04-19 10:20:30,186 - util.py[DEBUG]: Read 386 bytes from /etc/os-release 2024-04-19 10:20:30,187 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) 2024-04-19 10:20:30,187 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini 2024-04-19 10:20:30,187 - __init__.py[DEBUG]: Adding user ubuntu 2024-04-19 10:20:30,187 - subp.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'ubuntu', '--groups', 'adm,audio,cdrom,dialout,dip,floppy,netdev,plugdev,sudo,video', '--shell', '/bin/bash', '-m'] 2024-04-19 10:20:30,203 - subp.py[DEBUG]: Running command ['passwd', '-l', 'ubuntu'] with allowed return codes [0] (shell=False, capture=True) 2024-04-19 10:20:30,207 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) 2024-04-19 10:20:30,207 - util.py[DEBUG]: Read 1671 bytes from /etc/sudoers 2024-04-19 10:20:30,207 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 141 bytes 2024-04-19 10:20:30,208 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2024-04-19 10:20:30,208 - util.py[DEBUG]: Read 3254 bytes from /etc/ssh/sshd_config 2024-04-19 10:20:30,208 - util.py[DEBUG]: Changing the ownership of /home/ubuntu/.ssh to 1000:1000 2024-04-19 10:20:30,208 - util.py[DEBUG]: Writing to /home/ubuntu/.ssh/authorized_keys - wb: [600] 0 bytes 2024-04-19 10:20:30,208 - util.py[DEBUG]: Changing the ownership of /home/ubuntu/.ssh/authorized_keys to 1000:1000 2024-04-19 10:20:30,208 - util.py[DEBUG]: Reading from /home/ubuntu/.ssh/authorized_keys (quiet=False) 2024-04-19 10:20:30,208 - util.py[DEBUG]: Read 0 bytes from /home/ubuntu/.ssh/authorized_keys 2024-04-19 10:20:30,208 - util.py[DEBUG]: Writing to /home/ubuntu/.ssh/authorized_keys - wb: [600] 854 bytes 2024-04-19 10:20:30,208 - handlers.py[DEBUG]: finish: init-network/config-users_groups: SUCCESS: config-users_groups ran successfully 2024-04-19 10:20:30,208 - modules.py[DEBUG]: Running module ssh () with frequency once-per-instance 2024-04-19 10:20:30,208 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance 2024-04-19 10:20:30,208 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/8d3a9fbd-98c5-4d91-b0e8-0131c4343266/sem/config_ssh - wb: [644] 23 bytes 2024-04-19 10:20:30,209 - helpers.py[DEBUG]: Running config-ssh using lock () 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_ecdsa_key - wb: [600] 241 bytes 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_ecdsa_key.pub - wb: [644] 178 bytes 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_ed25519_key - wb: [600] 119 bytes 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_ed25519_key.pub - wb: [644] 98 bytes 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_rsa_key - wb: [600] 1704 bytes 2024-04-19 10:20:30,209 - util.py[DEBUG]: Writing to /etc/ssh/ssh_host_rsa_key.pub - wb: [644] 398 bytes 2024-04-19 10:20:30,210 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) 2024-04-19 10:20:30,210 - util.py[DEBUG]: Read 178 bytes from /etc/ssh/ssh_host_ecdsa_key.pub 2024-04-19 10:20:30,210 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) 2024-04-19 10:20:30,210 - util.py[DEBUG]: Read 98 bytes from /etc/ssh/ssh_host_ed25519_key.pub 2024-04-19 10:20:30,210 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) 2024-04-19 10:20:30,210 - util.py[DEBUG]: Read 398 bytes from /etc/ssh/ssh_host_rsa_key.pub 2024-04-19 10:20:30,210 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2024-04-19 10:20:30,210 - util.py[DEBUG]: Read 3254 bytes from /etc/ssh/sshd_config 2024-04-19 10:20:30,210 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 2024-04-19 10:20:30,210 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes 2024-04-19 10:20:30,210 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 2024-04-19 10:20:30,211 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False) 2024-04-19 10:20:30,211 - util.py[DEBUG]: Read 0 bytes from /root/.ssh/authorized_keys 2024-04-19 10:20:30,211 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes 2024-04-19 10:20:30,211 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully 2024-04-19 10:20:30,211 - main.py[DEBUG]: Ran 9 modules with 0 failures 2024-04-19 10:20:30,211 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpm1bpz5zb) - w: [644] 491 bytes/chars 2024-04-19 10:20:30,211 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-04-19 10:20:30,212 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-04-19 10:20:30,212 - util.py[DEBUG]: cloud-init mode 'init' took 0.130 seconds (0.13) 2024-04-19 10:20:30,212 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources