_raw: 2026-01-09 19:47:20,190 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:20,190 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg

_sourcetype: cloud-init

sourcetype: cloud-init


_raw: 2026-01-09 19:47:20,189 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:20,189 - handlers.py[DEBUG]: start: modules-final: running modules for final


_raw: 2026-01-09 19:47:20,189 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7f5dc38b6340>] - previously custom handler.


_raw: 2026-01-09 19:47:20,189 - socket.py[INFO]: Sending sd_notify(STATUS=Running (final stage))


_raw: 2026-01-09 19:47:20,189 - performance.py[DEBUG]: Waiting to start stage final took 1.949 seconds


_raw: 2026-01-09 19:47:18,240 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the final stage.)


_raw: 2026-01-09 19:47:18,240 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config


_raw: 2026-01-09 19:47:18,240 - performance.py[DEBUG]: cloud-init stage: 'modules-config' took 0.045 seconds


_raw: 2026-01-09 19:47:18,239 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpuu1ieq2q) - w: [644] 534 bytes/chars


_raw: 2026-01-09 19:47:18,239 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 19:47:18,239 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:18,239 - main.py[DEBUG]: Ran 6 modules with 0 failures


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu previously ran


_raw: 2026-01-09 19:47:18,239 - helpers.py[DEBUG]: config-byobu already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - modules.py[DEBUG]: Running module byobu (<module 'cloudinit.config.cc_byobu' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_byobu.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd previously ran


_raw: 2026-01-09 19:47:18,239 - helpers.py[DEBUG]: config-runcmd already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - modules.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure previously ran


_raw: 2026-01-09 19:47:18,239 - helpers.py[DEBUG]: config-apt-configure already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - modules.py[DEBUG]: Running module apt-configure (<module 'cloudinit.config.cc_apt_configure' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_configure.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,239 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords previously ran


_raw: 2026-01-09 19:47:18,239 - helpers.py[DEBUG]: config-set-passwords already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,238 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - modules.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale previously ran


_raw: 2026-01-09 19:47:18,238 - helpers.py[DEBUG]: config-locale already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,238 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - modules.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id previously ran


_raw: 2026-01-09 19:47:18,238 - helpers.py[DEBUG]: config-ssh-import-id already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:18,238 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - modules.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:18,238 - modules.py[INFO]: Skipping modules 'snap,keyboard,grub-dpkg,apt-pipelining,ntp,timezone,disable-ec2-metadata' because no applicable config is provided.


_raw: 2026-01-09 19:47:18,237 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 19:47:18,224 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,224 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:18,224 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:18,224 - main.py[INFO]: PID [1] started cloud-init 'modules:config'.


_raw: 2026-01-09 19:47:18,223 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,223 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:18,223 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:18,222 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:18,222 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,222 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,222 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:18,222 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,221 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,220 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:18,220 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,219 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,219 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:18,219 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,215 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,215 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:18,215 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,215 - handlers.py[DEBUG]: finish: modules-config/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:18,215 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:18,215 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-09 19:47:18,215 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-09 19:47:18,214 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-09 19:47:18,214 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-09 19:47:18,214 - handlers.py[DEBUG]: start: modules-config/check-cache: attempting to read from cache [trust]


_raw: 2026-01-09 19:47:18,213 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,213 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:18,213 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:18,213 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:18,213 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,212 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,212 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:18,212 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,211 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,211 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:18,211 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,209 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,209 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:18,209 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'


_raw: 2026-01-09 19:47:18,205 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp16safm6v) - w: [644] 545 bytes/chars


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading 532 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-09 19:47:18,205 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,203 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:18,202 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,201 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,201 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:18,201 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,199 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,199 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:18,199 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,195 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:18,195 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:18,195 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:18,195 - handlers.py[DEBUG]: start: modules-config: running modules for config


_raw: 2026-01-09 19:47:18,195 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7f5dc38b6340>] - previously custom handler.


_raw: 2026-01-09 19:47:18,194 - socket.py[INFO]: Sending sd_notify(STATUS=Running (config stage))


_raw: 2026-01-09 19:47:18,194 - performance.py[DEBUG]: Waiting to start stage config took 0.423 seconds


_raw: 2026-01-09 19:47:17,771 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the config stage.)


_raw: 2026-01-09 19:47:17,771 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources


_raw: 2026-01-09 19:47:17,771 - performance.py[DEBUG]: cloud-init stage: 'init-network' took 0.171 seconds


_raw: 2026-01-09 19:47:17,771 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpl5yt7qgs) - w: [644] 532 bytes/chars


_raw: 2026-01-09 19:47:17,771 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 19:47:17,771 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:17,771 - main.py[DEBUG]: Ran 8 modules with 0 failures


_raw: 2026-01-09 19:47:17,771 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran


_raw: 2026-01-09 19:47:17,771 - helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,770 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance


_raw: 2026-01-09 19:47:17,770 - modules.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:17,770 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran


_raw: 2026-01-09 19:47:17,770 - helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,770 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance


_raw: 2026-01-09 19:47:17,770 - modules.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:17,770 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully and took 0.001 seconds


_raw: 2026-01-09 19:47:17,770 - distros[INFO]: /var/lib/cloud/data/previous-hostname differs from /etc/hostname, assuming user maintained hostname.


_raw: 2026-01-09 19:47:17,770 - util.py[DEBUG]: Reading 28 bytes from /etc/hostname


_raw: 2026-01-09 19:47:17,770 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False)


_raw: 2026-01-09 19:47:17,770 - util.py[DEBUG]: Reading 5 bytes from /var/lib/cloud/data/previous-hostname


_raw: 2026-01-09 19:47:17,769 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False)


_raw: 2026-01-09 19:47:17,769 - cc_update_hostname.py[DEBUG]: Updating hostname to cptc11-finals-team-01-kali01-345d9702.novalocal (kali)


_raw: 2026-01-09 19:47:17,769 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7f5dc2fcac10>)


_raw: 2026-01-09 19:47:17,769 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always


_raw: 2026-01-09 19:47:17,769 - modules.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always


_raw: 2026-01-09 19:47:17,769 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran


_raw: 2026-01-09 19:47:17,769 - helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,769 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance


_raw: 2026-01-09 19:47:17,769 - modules.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:17,769 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts previously ran


_raw: 2026-01-09 19:47:17,768 - helpers.py[DEBUG]: config-mounts already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,768 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance


_raw: 2026-01-09 19:47:17,768 - modules.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:17,768 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully and took 0.025 seconds


_raw: 2026-01-09 19:47:17,768 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)


_raw: 2026-01-09 19:47:17,768 - performance.py[DEBUG]: Running ('resize2fs', '/dev/vda1') took 0.024 seconds


_raw: 2026-01-09 19:47:17,744 - subp.py[DEBUG]: Running command ('resize2fs', '/dev/vda1') with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,744 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/vda1


_raw: 2026-01-09 19:47:17,744 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/vda1 mnt_point=/ path=/


_raw: 2026-01-09 19:47:17,744 - util.py[DEBUG]: Reading 2225 bytes from /proc/395/mountinfo


_raw: 2026-01-09 19:47:17,743 - util.py[DEBUG]: Reading from /proc/395/mountinfo (quiet=False)


_raw: 2026-01-09 19:47:17,743 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f5dc2fcae90>)


_raw: 2026-01-09 19:47:17,743 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always


_raw: 2026-01-09 19:47:17,743 - modules.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_resizefs.py'>) with frequency always


_raw: 2026-01-09 19:47:17,743 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully and took 0.054 seconds


_raw: 2026-01-09 19:47:17,743 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/vda, 1)


_raw: 2026-01-09 19:47:17,743 - performance.py[DEBUG]: Resizing devices took 0.051 seconds


_raw: 2026-01-09 19:47:17,743 - performance.py[DEBUG]: Running ['growpart', '--dry-run', '/dev/vda', '1'] took 0.046 seconds


_raw: 2026-01-09 19:47:17,696 - subp.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/vda', '1'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading 2225 bytes from /proc/395/mountinfo


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading from /proc/395/mountinfo (quiet=False)


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading 6 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev (quiet=False)


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading 2 bytes from /sys/class/block/vda1/partition


_raw: 2026-01-09 19:47:17,693 - util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)


_raw: 2026-01-09 19:47:17,693 - cc_growpart.py[DEBUG]: growpart found fs=ext4


_raw: 2026-01-09 19:47:17,689 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,689 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg.  Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False}


_raw: 2026-01-09 19:47:17,689 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f5dc336fb60>)


_raw: 2026-01-09 19:47:17,689 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always


_raw: 2026-01-09 19:47:17,689 - modules.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_growpart.py'>) with frequency always


_raw: 2026-01-09 19:47:17,689 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random previously ran


_raw: 2026-01-09 19:47:17,689 - helpers.py[DEBUG]: config-seed_random already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,689 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance


_raw: 2026-01-09 19:47:17,688 - modules.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_seed_random.py'>) with frequency once-per-instance


_raw: 2026-01-09 19:47:17,688 - modules.py[INFO]: Skipping modules 'bootcmd,write-files,disk_setup,update_etc_hosts,ca-certs,rsyslog' because no applicable config is provided.


_raw: 2026-01-09 19:47:17,687 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 19:47:17,675 - main.py[DEBUG]: no di_report found in config.


_raw: 2026-01-09 19:47:17,675 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource


_raw: 2026-01-09 19:47:17,675 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10566 bytes


_raw: 2026-01-09 19:47:17,674 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource


_raw: 2026-01-09 19:47:17,672 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,672 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,672 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,668 - util.py[DEBUG]: Reading 157773 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json


_raw: 2026-01-09 19:47:17,665 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)


_raw: 2026-01-09 19:47:17,664 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,664 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,664 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpnzqi2snj) - w: [600] 12636 bytes/chars


_raw: 2026-01-09 19:47:17,664 - util.py[DEBUG]: Reading 11479 bytes from /run/cloud-init/instance-data-sensitive.json


_raw: 2026-01-09 19:47:17,664 - util.py[DEBUG]: Reading from /run/cloud-init/instance-data-sensitive.json (quiet=False)


_raw: 2026-01-09 19:47:17,664 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/combined-cloud-config.json (via temporary file /run/cloud-init/tmpxnvo5576) - w: [600] 4960 bytes/chars


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,662 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:17,661 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,660 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,660 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:17,660 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,659 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,659 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:17,659 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,654 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,654 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:17,654 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,654 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2


_raw: 2026-01-09 19:47:17,654 - stages.py[DEBUG]: no vendordata2 from datasource


_raw: 2026-01-09 19:47:17,654 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2


_raw: 2026-01-09 19:47:17,654 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data


_raw: 2026-01-09 19:47:17,654 - stages.py[DEBUG]: no vendordata from datasource


_raw: 2026-01-09 19:47:17,654 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data


_raw: 2026-01-09 19:47:17,654 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency always


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,654 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,652 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always


_raw: 2026-01-09 19:47:17,651 - util.py[DEBUG]: Attempting to load yaml from string of length 383 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'}


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always


_raw: 2026-01-09 19:47:17,651 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']]


_raw: 2026-01-09 19:47:17,651 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]


_raw: 2026-01-09 19:47:17,650 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data


_raw: 2026-01-09 19:47:17,650 - helpers.py[DEBUG]: consume_data already ran (freq=once-per-instance)


_raw: 2026-01-09 19:47:17,649 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname


_raw: 2026-01-09 19:47:17,649 - util.py[DEBUG]: Reading 84 bytes from /var/lib/cloud/data/set-hostname


_raw: 2026-01-09 19:47:17,649 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False)


_raw: 2026-01-09 19:47:17,647 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource


_raw: 2026-01-09 19:47:17,647 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource


_raw: 2026-01-09 19:47:17,646 - net[DEBUG]: Detected interfaces {'eth0': {'downable': False, 'device_id': '0x0001', 'driver': 'virtio_net', 'mac': 'fa:16:3e:04:db:dd', 'name': 'eth0', 'up': True}, 'lo': {'downable': False, 'device_id': None, 'driver': None, 'mac': '00:00:00:00:00:00', 'name': 'lo', 'up': True}}


_raw: 2026-01-09 19:47:17,645 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,643 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 8 bytes from /sys/class/net/lo/operstate


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 3 bytes from /sys/class/net/eth0/operstate


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/lo/addr_assign_type


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 23 bytes from /sys/class/net/lo/uevent


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-09 19:47:17,643 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/eth0/addr_assign_type


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading 25 bytes from /sys/class/net/eth0/uevent


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-09 19:47:17,642 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 19:47:17,642 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'mtu': None, 'subnets': [{'type': 'dhcp4'}], 'mac_address': 'fa:16:3e:04:db:dd', 'name': 'eth0'}, {'type': 'nameserver', 'address': '1.1.1.1'}]}


_raw: 2026-01-09 19:47:17,642 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed


_raw: 2026-01-09 19:47:17,642 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy


_raw: 2026-01-09 19:47:17,642 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}}


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled


_raw: 2026-01-09 19:47:17,642 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False)


_raw: 2026-01-09 19:47:17,641 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot


_raw: 2026-01-09 19:47:17,641 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}}


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False)


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-09 19:47:17,641 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-09 19:47:17,641 - main.py[DEBUG]: [net] init will now be targeting instance id: 0c732108-879b-4bbb-954b-47a808765007. new=False


_raw: 2026-01-09 19:47:17,641 - stages.py[INFO]: Not re-loading configuration, instance id and datasource have not changed.


_raw: 2026-01-09 19:47:17,640 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10575 bytes


_raw: 2026-01-09 19:47:17,640 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:17,640 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:17,640 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:17,639 - util.py[DEBUG]: Reading 37 bytes from /var/lib/cloud/data/instance-id


_raw: 2026-01-09 19:47:17,639 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)


_raw: 2026-01-09 19:47:17,639 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 74 bytes


_raw: 2026-01-09 19:47:17,639 - util.py[DEBUG]: Reading 74 bytes from /var/lib/cloud/instances/0c732108-879b-4bbb-954b-47a808765007/datasource


_raw: 2026-01-09 19:47:17,639 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/0c732108-879b-4bbb-954b-47a808765007/datasource (quiet=False)


_raw: 2026-01-09 19:47:17,638 - stages.py[INFO]: Instance link already exists, not recreating it.


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:17,637 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,635 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,635 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:17,635 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,634 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,634 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:17,634 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,630 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,630 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:17,630 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,630 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:17,630 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:17,630 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-09 19:47:17,630 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-09 19:47:17,629 - util.py[DEBUG]: Reading 10575 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-09 19:47:17,629 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-09 19:47:17,629 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]


_raw: 2026-01-09 19:47:17,627 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,625 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,623 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:17,623 - util.py[DEBUG]: Reading 4 bytes from /var/lib/cloud/data/python-version


_raw: 2026-01-09 19:47:17,623 - util.py[DEBUG]: Reading from /var/lib/cloud/data/python-version (quiet=False)


_raw: 2026-01-09 19:47:17,623 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4


_raw: 2026-01-09 19:47:17,622 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes


_raw: 2026-01-09 19:47:17,621 - main.py[DEBUG]: No kernel command line url found.


_raw: 2026-01-09 19:47:17,620 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,620 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:17,619 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,617 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,617 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:17,617 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,616 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,616 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:17,616 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:17,612 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'


_raw: 2026-01-09 19:47:17,612 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpaqc_6hz2) - w: [644] 534 bytes/chars


_raw: 2026-01-09 19:47:17,611 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:17,611 - util.py[DEBUG]: Reading 531 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-09 19:47:17,611 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:17,609 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,607 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,607 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:17,607 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,606 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,605 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:17,605 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,601 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:17,600 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:17,600 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:17,600 - handlers.py[DEBUG]: start: init-network: searching for network datasources


_raw: 2026-01-09 19:47:17,600 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7f5dc38b6340>] - previously custom handler.


_raw: 2026-01-09 19:47:17,600 - socket.py[INFO]: Sending sd_notify(STATUS=Running (network stage))


_raw: 2026-01-09 19:47:17,600 - performance.py[DEBUG]: Waiting to start stage network took 6.524 seconds


_raw: 2026-01-09 19:47:11,076 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the network stage.)


_raw: 2026-01-09 19:47:11,076 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources


_raw: 2026-01-09 19:47:11,076 - performance.py[DEBUG]: cloud-init stage: 'init-local' took 0.081 seconds


_raw: 2026-01-09 19:47:11,076 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp09u1vnc2) - w: [644] 531 bytes/chars


_raw: 2026-01-09 19:47:11,075 - util.py[DEBUG]: Reading 11 bytes from /proc/uptime


_raw: 2026-01-09 19:47:11,075 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 19:47:11,075 - main.py[DEBUG]: [local] Exiting. datasource DataSourceConfigDrive [net,ver=2][source=/dev/sr0] not in local mode.


_raw: 2026-01-09 19:47:11,075 - util.py[DEBUG]: Writing to /run/cloud-init/.skip-network - wb: [644] 0 bytes


_raw: 2026-01-09 19:47:11,075 - main.py[DEBUG]: Network connectivity determined unnecessary for cloud-init's network stage. Reason: user data: cloud-config does not contain network requiring elements, vendor data: no configuration found, vendor data2: no configuration found


_raw: 2026-01-09 19:47:11,074 - net[DEBUG]: Detected interfaces {'eth0': {'downable': True, 'device_id': '0x0001', 'driver': 'virtio_net', 'mac': 'fa:16:3e:04:db:dd', 'name': 'eth0', 'up': False}, 'lo': {'downable': False, 'device_id': None, 'driver': None, 'mac': '00:00:00:00:00:00', 'name': 'lo', 'up': True}}


_raw: 2026-01-09 19:47:11,071 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:11,066 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading 8 bytes from /sys/class/net/lo/operstate


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading 5 bytes from /sys/class/net/eth0/operstate


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-09 19:47:11,066 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/lo/addr_assign_type


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 23 bytes from /sys/class/net/lo/uevent


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-09 19:47:11,065 - net[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/eth0/addr_assign_type


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading 25 bytes from /sys/class/net/eth0/uevent


_raw: 2026-01-09 19:47:11,065 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)


_raw: 2026-01-09 19:47:11,065 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'mtu': None, 'subnets': [{'type': 'dhcp4'}], 'mac_address': 'fa:16:3e:04:db:dd', 'name': 'eth0'}, {'type': 'nameserver', 'address': '1.1.1.1'}]}


_raw: 2026-01-09 19:47:11,065 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed


_raw: 2026-01-09 19:47:11,065 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy


_raw: 2026-01-09 19:47:11,064 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}}


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False)


_raw: 2026-01-09 19:47:11,064 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-09 19:47:11,064 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-09 19:47:11,063 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname


_raw: 2026-01-09 19:47:11,063 - util.py[DEBUG]: Reading 84 bytes from /var/lib/cloud/data/set-hostname


_raw: 2026-01-09 19:47:11,063 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False)


_raw: 2026-01-09 19:47:11,062 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 19:47:11,062 - main.py[DEBUG]: [local] init will now be targeting instance id: 0c732108-879b-4bbb-954b-47a808765007. new=False


_raw: 2026-01-09 19:47:11,062 - stages.py[INFO]: Not re-loading configuration, instance id and datasource have not changed.


_raw: 2026-01-09 19:47:11,062 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10575 bytes


_raw: 2026-01-09 19:47:11,061 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:11,061 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:11,061 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes


_raw: 2026-01-09 19:47:11,060 - util.py[DEBUG]: Reading 37 bytes from /var/lib/cloud/data/instance-id


_raw: 2026-01-09 19:47:11,060 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)


_raw: 2026-01-09 19:47:11,060 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 74 bytes


_raw: 2026-01-09 19:47:11,059 - util.py[DEBUG]: Reading 74 bytes from /var/lib/cloud/instances/0c732108-879b-4bbb-954b-47a808765007/datasource


_raw: 2026-01-09 19:47:11,059 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/0c732108-879b-4bbb-954b-47a808765007/datasource (quiet=False)


_raw: 2026-01-09 19:47:11,059 - stages.py[INFO]: Instance link already exists, not recreating it.


_raw: 2026-01-09 19:47:11,058 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpcwhr71o3) - w: [644] 4014 bytes/chars


_raw: 2026-01-09 19:47:11,058 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpf1hd_sa4) - w: [600] 11479 bytes/chars


_raw: 2026-01-09 19:47:11,058 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-configdrive'


_raw: 2026-01-09 19:47:11,057 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-configdrive - wb: [644] 12 bytes


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 19:47:11,055 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:11,053 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,053 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 19:47:11,053 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 19:47:11,052 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,052 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 19:47:11,052 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 19:47:11,048 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 19:47:11,048 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 19:47:11,047 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 19:47:11,047 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: restored from checked cache: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:11,047 - stages.py[DEBUG]: restored from checked cache: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 19:47:11,047 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_uuid


_raw: 2026-01-09 19:47:11,038 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-09 19:47:11,038 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-09 19:47:11,038 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check]


_raw: 2026-01-09 19:47:11,038 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished


_raw: 2026-01-09 19:47:11,037 - util.py[DEBUG]: Reading 4 bytes from /var/lib/cloud/data/python-version


_raw: 2026-01-09 19:47:11,037 - util.py[DEBUG]: Reading from /var/lib/cloud/data/python-version (quiet=False)


_raw: 2026-01-09 19:47:11,037 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4


_raw: 2026-01-09 19:47:11,036 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes


_raw: 2026-01-09 19:47:11,034 - main.py[DEBUG]: No kernel command line url found.


_raw: 2026-01-09 19:47:11,034 - main.py[INFO]: PID [1] started cloud-init 'init-local'.


_raw: 2026-01-09 18:43:43,992 - socket.py[INFO]: Sending sd_notify(STOPPING=1)


_raw: 2026-01-09 18:43:43,992 - socket.py[INFO]: Sending sd_notify(STATUS=Completed)


_raw: 2026-01-09 18:43:43,992 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final


_raw: 2026-01-09 18:43:43,992 - performance.py[DEBUG]: cloud-init stage: 'modules-final' took 0.100 seconds


_raw: 2026-01-09 18:43:43,992 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json'


_raw: 2026-01-09 18:43:43,992 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmpugyajdvn) - w: [644] 101 bytes/chars


_raw: 2026-01-09 18:43:43,991 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpv7g4c5d8) - w: [644] 536 bytes/chars


_raw: 2026-01-09 18:43:43,991 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:43,991 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:43,991 - main.py[DEBUG]: Ran 11 modules with 0 failures


_raw: 2026-01-09 18:43:43,991 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully and took 0.013 seconds


_raw: 2026-01-09 18:43:43,990 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 50 bytes


_raw: 2026-01-09 18:43:43,990 - log_util.py[DEBUG]: Cloud-init v. 25.3 finished at Fri, 09 Jan 2026 18:43:43 +0000. Datasource DataSourceConfigDrive [net,ver=2][source=/dev/sr0].  Up 14.48 seconds


_raw: 2026-01-09 18:43:43,990 - performance.py[DEBUG]: Rendering jinja2 template took 0.012 seconds


_raw: 2026-01-09 18:43:43,978 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:43,978 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:43,978 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7f3e93b08d60>)


_raw: 2026-01-09 18:43:43,978 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always


_raw: 2026-01-09 18:43:43,978 - modules.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_final_message.py'>) with frequency always


_raw: 2026-01-09 18:43:43,978 - handlers.py[DEBUG]: finish: modules-final/config-install-hotplug: SUCCESS: config-install-hotplug previously ran


_raw: 2026-01-09 18:43:43,977 - helpers.py[DEBUG]: config-install-hotplug already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: start: modules-final/config-install-hotplug: running config-install-hotplug with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - modules.py[DEBUG]: Running module install-hotplug (<module 'cloudinit.config.cc_install_hotplug' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_install_hotplug.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console previously ran


_raw: 2026-01-09 18:43:43,977 - helpers.py[DEBUG]: config-keys-to-console already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - modules.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints previously ran


_raw: 2026-01-09 18:43:43,977 - helpers.py[DEBUG]: config-ssh-authkey-fingerprints already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - modules.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,977 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user previously ran


_raw: 2026-01-09 18:43:43,977 - helpers.py[DEBUG]: config-scripts-user already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,976 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance


_raw: 2026-01-09 18:43:43,976 - modules.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,976 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance previously ran


_raw: 2026-01-09 18:43:43,976 - helpers.py[DEBUG]: config-scripts-per-instance already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,976 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance


_raw: 2026-01-09 18:43:43,976 - modules.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,976 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully and took 0.001 seconds


_raw: 2026-01-09 18:43:43,975 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7f3e93b08c30>)


_raw: 2026-01-09 18:43:43,975 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always


_raw: 2026-01-09 18:43:43,975 - modules.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always


_raw: 2026-01-09 18:43:43,975 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once previously ran


_raw: 2026-01-09 18:43:43,975 - helpers.py[DEBUG]: config-scripts-per-once already ran (freq=once)


_raw: 2026-01-09 18:43:43,972 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once


_raw: 2026-01-09 18:43:43,972 - modules.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once


_raw: 2026-01-09 18:43:43,972 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously ran


_raw: 2026-01-09 18:43:43,972 - helpers.py[DEBUG]: config-scripts-vendor already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,971 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - modules.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc previously ran


_raw: 2026-01-09 18:43:43,971 - helpers.py[DEBUG]: config-reset_rmc already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,971 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - modules.py[DEBUG]: Running module reset_rmc (<module 'cloudinit.config.cc_reset_rmc' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_reset_rmc.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install previously ran


_raw: 2026-01-09 18:43:43,971 - helpers.py[DEBUG]: config-package-update-upgrade-install already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:43,971 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - modules.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:43,971 - modules.py[INFO]: Skipping modules 'fan,landscape,lxd,write-files-deferred,puppet,chef,mcollective,salt-minion,phone-home,power-state-change' because no applicable config is provided.


_raw: 2026-01-09 18:43:43,969 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 18:43:43,948 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,948 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:43,948 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:43,948 - main.py[INFO]: PID [1] started cloud-init 'modules:final'.


_raw: 2026-01-09 18:43:43,947 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,947 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:43,947 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:43,947 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:43,947 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,946 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,942 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:43,942 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,941 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,941 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:43,941 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,940 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,940 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:43,940 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,932 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,932 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:43,932 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,932 - handlers.py[DEBUG]: finish: modules-final/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 18:43:43,932 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 18:43:43,932 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-09 18:43:43,932 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-09 18:43:43,931 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-09 18:43:43,931 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-09 18:43:43,931 - handlers.py[DEBUG]: start: modules-final/check-cache: attempting to read from cache [trust]


_raw: 2026-01-09 18:43:43,926 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,926 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:43,926 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:43,926 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:43,926 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,925 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,925 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:43,925 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,924 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,924 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:43,924 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,922 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,922 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:43,922 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,915 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,915 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:43,915 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,915 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:43,915 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:43,912 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'


_raw: 2026-01-09 18:43:43,911 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpthy5nm8_) - w: [644] 546 bytes/chars


_raw: 2026-01-09 18:43:43,911 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:43,911 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:43,910 - util.py[DEBUG]: Reading 534 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-09 18:43:43,909 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


_raw: 2026-01-09 18:43:43,905 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,905 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:43,905 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:43,905 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:43,905 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,904 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,904 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:43,904 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,902 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,902 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:43,902 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,900 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,900 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:43,900 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,892 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:43,892 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:43,892 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:43,891 - handlers.py[DEBUG]: start: modules-final: running modules for final


_raw: 2026-01-09 18:43:43,891 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7f3e9433a340>] - previously custom handler.


_raw: 2026-01-09 18:43:43,891 - socket.py[INFO]: Sending sd_notify(STATUS=Running (final stage))


_raw: 2026-01-09 18:43:43,891 - performance.py[DEBUG]: Waiting to start stage final took 1.964 seconds


_raw: 2026-01-09 18:43:41,926 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the final stage.)


_raw: 2026-01-09 18:43:41,926 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config


_raw: 2026-01-09 18:43:41,926 - performance.py[DEBUG]: cloud-init stage: 'modules-config' took 0.059 seconds


_raw: 2026-01-09 18:43:41,926 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpznv4pvq_) - w: [644] 534 bytes/chars


_raw: 2026-01-09 18:43:41,926 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:41,926 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:41,926 - main.py[DEBUG]: Ran 6 modules with 0 failures


_raw: 2026-01-09 18:43:41,926 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu previously ran


_raw: 2026-01-09 18:43:41,926 - helpers.py[DEBUG]: config-byobu already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - modules.py[DEBUG]: Running module byobu (<module 'cloudinit.config.cc_byobu' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_byobu.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd previously ran


_raw: 2026-01-09 18:43:41,925 - helpers.py[DEBUG]: config-runcmd already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - modules.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure previously ran


_raw: 2026-01-09 18:43:41,925 - helpers.py[DEBUG]: config-apt-configure already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - modules.py[DEBUG]: Running module apt-configure (<module 'cloudinit.config.cc_apt_configure' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_configure.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords previously ran


_raw: 2026-01-09 18:43:41,925 - helpers.py[DEBUG]: config-set-passwords already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - modules.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale previously ran


_raw: 2026-01-09 18:43:41,925 - helpers.py[DEBUG]: config-locale already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,925 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance


_raw: 2026-01-09 18:43:41,924 - modules.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,924 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id previously ran


_raw: 2026-01-09 18:43:41,924 - helpers.py[DEBUG]: config-ssh-import-id already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,924 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance


_raw: 2026-01-09 18:43:41,924 - modules.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,924 - modules.py[INFO]: Skipping modules 'snap,keyboard,grub-dpkg,apt-pipelining,ntp,timezone,disable-ec2-metadata' because no applicable config is provided.


_raw: 2026-01-09 18:43:41,923 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-09 18:43:41,903 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,903 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:41,903 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:41,903 - main.py[INFO]: PID [1] started cloud-init 'modules:config'.


_raw: 2026-01-09 18:43:41,902 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,902 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:41,902 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:41,902 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:41,901 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,901 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,901 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:41,901 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,900 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,900 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:41,899 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,898 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,898 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:41,898 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,894 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,894 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:41,894 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,894 - handlers.py[DEBUG]: finish: modules-config/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 18:43:41,893 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-09 18:43:41,893 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-09 18:43:41,893 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-09 18:43:41,893 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-09 18:43:41,893 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-09 18:43:41,893 - handlers.py[DEBUG]: start: modules-config/check-cache: attempting to read from cache [trust]


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:41,891 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,889 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,889 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:41,889 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,888 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,888 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:41,887 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:41,883 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'


_raw: 2026-01-09 18:43:41,882 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp2lqf394e) - w: [644] 545 bytes/chars


_raw: 2026-01-09 18:43:41,882 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:41,882 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:41,882 - util.py[DEBUG]: Reading 532 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-09 18:43:41,882 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


_raw: 2026-01-09 18:43:41,879 - util.py[DEBUG]: Attempting to load yaml from string of length 447 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,879 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-09 18:43:41,879 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-09 18:43:41,878 - util.py[DEBUG]: loaded blob returned None, returning default.


_raw: 2026-01-09 18:43:41,878 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,878 - util.py[DEBUG]: Attempting to load yaml from string of length 39 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,878 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-09 18:43:41,878 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,876 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,876 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-09 18:43:41,876 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,875 - util.py[DEBUG]: Attempting to load yaml from string of length 513 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,875 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-09 18:43:41,874 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,868 - util.py[DEBUG]: Attempting to load yaml from string of length 2729 with allowed root types (<class 'dict'>,)


_raw: 2026-01-09 18:43:41,868 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-09 18:43:41,867 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-09 18:43:41,867 - handlers.py[DEBUG]: start: modules-config: running modules for config


_raw: 2026-01-09 18:43:41,867 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7f3e9433a340>] - previously custom handler.


_raw: 2026-01-09 18:43:41,866 - socket.py[INFO]: Sending sd_notify(STATUS=Running (config stage))


_raw: 2026-01-09 18:43:41,866 - performance.py[DEBUG]: Waiting to start stage config took 0.411 seconds


_raw: 2026-01-09 18:43:41,455 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the config stage.)


_raw: 2026-01-09 18:43:41,455 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources


_raw: 2026-01-09 18:43:41,455 - performance.py[DEBUG]: cloud-init stage: 'init-network' took 0.166 seconds


_raw: 2026-01-09 18:43:41,454 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpvhu279n1) - w: [644] 532 bytes/chars


_raw: 2026-01-09 18:43:41,454 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-09 18:43:41,454 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-09 18:43:41,454 - main.py[DEBUG]: Ran 8 modules with 0 failures


_raw: 2026-01-09 18:43:41,454 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran


_raw: 2026-01-09 18:43:41,454 - helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,454 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance


_raw: 2026-01-09 18:43:41,454 - modules.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,454 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran


_raw: 2026-01-09 18:43:41,454 - helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,454 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance


_raw: 2026-01-09 18:43:41,454 - modules.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,454 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully and took 0.001 seconds


_raw: 2026-01-09 18:43:41,453 - distros[INFO]: /var/lib/cloud/data/previous-hostname differs from /etc/hostname, assuming user maintained hostname.


_raw: 2026-01-09 18:43:41,453 - util.py[DEBUG]: Reading 28 bytes from /etc/hostname


_raw: 2026-01-09 18:43:41,453 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False)


_raw: 2026-01-09 18:43:41,453 - util.py[DEBUG]: Reading 5 bytes from /var/lib/cloud/data/previous-hostname


_raw: 2026-01-09 18:43:41,453 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False)


_raw: 2026-01-09 18:43:41,453 - cc_update_hostname.py[DEBUG]: Updating hostname to cptc11-finals-team-06-kali06-345d9702.novalocal (kali)


_raw: 2026-01-09 18:43:41,453 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7f3e93a6ec10>)


_raw: 2026-01-09 18:43:41,453 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always


_raw: 2026-01-09 18:43:41,453 - modules.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always


_raw: 2026-01-09 18:43:41,453 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran


_raw: 2026-01-09 18:43:41,452 - helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,452 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance


_raw: 2026-01-09 18:43:41,452 - modules.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,452 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts previously ran


_raw: 2026-01-09 18:43:41,452 - helpers.py[DEBUG]: config-mounts already ran (freq=once-per-instance)


_raw: 2026-01-09 18:43:41,452 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance


_raw: 2026-01-09 18:43:41,452 - modules.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance


_raw: 2026-01-09 18:43:41,452 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully and took 0.026 seconds


_raw: 2026-01-09 18:43:41,452 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)


_raw: 2026-01-09 18:43:41,452 - performance.py[DEBUG]: Running ('resize2fs', '/dev/vda1') took 0.026 seconds


_raw: 2026-01-09 18:43:41,426 - subp.py[DEBUG]: Running command ('resize2fs', '/dev/vda1') with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 18:43:41,426 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/vda1


_raw: 2026-01-09 18:43:41,426 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/vda1 mnt_point=/ path=/


_raw: 2026-01-09 18:43:41,426 - util.py[DEBUG]: Reading 2211 bytes from /proc/392/mountinfo


_raw: 2026-01-09 18:43:41,426 - util.py[DEBUG]: Reading from /proc/392/mountinfo (quiet=False)


_raw: 2026-01-09 18:43:41,426 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f3e93a6ee90>)


_raw: 2026-01-09 18:43:41,426 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always


_raw: 2026-01-09 18:43:41,426 - modules.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_resizefs.py'>) with frequency always


_raw: 2026-01-09 18:43:41,425 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully and took 0.050 seconds


_raw: 2026-01-09 18:43:41,425 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/vda, 1)


_raw: 2026-01-09 18:43:41,425 - performance.py[DEBUG]: Resizing devices took 0.046 seconds


_raw: 2026-01-09 18:43:41,425 - performance.py[DEBUG]: Running ['growpart', '--dry-run', '/dev/vda', '1'] took 0.044 seconds


_raw: 2026-01-09 18:43:41,380 - subp.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/vda', '1'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-09 18:43:41,380 - util.py[DEBUG]: Reading 2211 bytes from /proc/392/mountinfo


_raw: 2026-01-09 18:43:41,380 - util.py[DEBUG]: Reading from /proc/392/mountinfo (quiet=False)


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading from /proc/392/mountinfo (quiet=False)


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading 6 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev (quiet=False)


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading 2 bytes from /sys/class/block/vda1/partition


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)


_raw: 2026-01-09 18:43:41,379 - cc_growpart.py[DEBUG]: growpart found fs=ext4


_raw: 2026-01-09 18:43:41,379 - util.py[DEBUG]: Reading 2211 bytes from /proc/392/mountinfo


_raw: 2026-01-09 18:43:41,375 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)