_raw: 2026-01-10 02:43:59,975 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7fc2faf54d60>)


_raw: 2026-01-10 02:43:59,975 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always

_sourcetype: cloud-init

sourcetype: cloud-init


_raw: 2026-01-10 02:43:59,975 - 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-10 02:43:59,975 - handlers.py[DEBUG]: finish: modules-final/config-install-hotplug: SUCCESS: config-install-hotplug previously ran


_raw: 2026-01-10 02:43:59,975 - helpers.py[DEBUG]: config-install-hotplug already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,975 - handlers.py[DEBUG]: start: modules-final/config-install-hotplug: running config-install-hotplug with frequency once-per-instance


_raw: 2026-01-10 02:43:59,974 - 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-10 02:43:59,974 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console previously ran


_raw: 2026-01-10 02:43:59,974 - helpers.py[DEBUG]: config-keys-to-console already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,974 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance


_raw: 2026-01-10 02:43:59,974 - 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-10 02:43:59,974 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints previously ran


_raw: 2026-01-10 02:43:59,974 - helpers.py[DEBUG]: config-ssh-authkey-fingerprints already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,974 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance


_raw: 2026-01-10 02:43:59,974 - 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-10 02:43:59,974 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user previously ran


_raw: 2026-01-10 02:43:59,974 - helpers.py[DEBUG]: config-scripts-user already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,974 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance


_raw: 2026-01-10 02:43:59,974 - 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-10 02:43:59,974 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance previously ran


_raw: 2026-01-10 02:43:59,974 - helpers.py[DEBUG]: config-scripts-per-instance already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,974 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance


_raw: 2026-01-10 02:43:59,974 - 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-10 02:43:59,974 - 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-10 02:43:59,973 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7fc2faf54c30>)


_raw: 2026-01-10 02:43:59,973 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always


_raw: 2026-01-10 02:43:59,973 - 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-10 02:43:59,973 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once previously ran


_raw: 2026-01-10 02:43:59,973 - helpers.py[DEBUG]: config-scripts-per-once already ran (freq=once)


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


_raw: 2026-01-10 02:43:59,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-10 02:43:59,972 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor previously ran


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


_raw: 2026-01-10 02:43:59,972 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance


_raw: 2026-01-10 02:43:59,972 - 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-10 02:43:59,972 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc previously ran


_raw: 2026-01-10 02:43:59,972 - helpers.py[DEBUG]: config-reset_rmc already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:59,972 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance


_raw: 2026-01-10 02:43:59,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-10 02:43:59,971 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install previously ran


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


_raw: 2026-01-10 02:43:59,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-10 02:43:59,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-10 02:43:59,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-10 02:43:59,970 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


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


_raw: 2026-01-10 02:43:59,954 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:59,954 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:59,954 - main.py[INFO]: PID [1] started cloud-init 'modules:final'.


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


_raw: 2026-01-10 02:43:59,952 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:59,952 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:59,952 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:59,952 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:59,952 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,950 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:59,950 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,949 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:59,949 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:59,945 - 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-10 02:43:59,945 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-10 02:43:59,945 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-10 02:43:59,945 - handlers.py[DEBUG]: start: modules-final/check-cache: attempting to read from cache [trust]


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


_raw: 2026-01-10 02:43:59,944 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:59,944 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:59,943 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:59,943 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:59,943 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,942 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:59,942 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


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


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


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


_raw: 2026-01-10 02:43:59,937 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:59,937 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:59,936 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-10 02:43:59,936 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


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


_raw: 2026-01-10 02:43:59,936 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmptkx18a09) - w: [644] 547 bytes/chars


_raw: 2026-01-10 02:43:59,936 - util.py[DEBUG]: Reading 535 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-10 02:43:59,936 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


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


_raw: 2026-01-10 02:43:59,933 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:59,933 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:59,932 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:59,932 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:59,932 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,930 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:59,930 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,926 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:59,926 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:59,922 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:59,922 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:59,922 - handlers.py[DEBUG]: start: modules-final: running modules for final


_raw: 2026-01-10 02:43:59,922 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7fc2fb786340>] - previously custom handler.


_raw: 2026-01-10 02:43:59,922 - socket.py[INFO]: Sending sd_notify(STATUS=Running (final stage))


_raw: 2026-01-10 02:43:59,922 - performance.py[DEBUG]: Waiting to start stage final took 2.513 seconds


_raw: 2026-01-10 02:43:57,408 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the final stage.)


_raw: 2026-01-10 02:43:57,408 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config


_raw: 2026-01-10 02:43:57,408 - performance.py[DEBUG]: cloud-init stage: 'modules-config' took 0.046 seconds


_raw: 2026-01-10 02:43:57,408 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpj5jppghz) - w: [644] 535 bytes/chars


_raw: 2026-01-10 02:43:57,407 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-10 02:43:57,407 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-10 02:43:57,407 - main.py[DEBUG]: Ran 6 modules with 0 failures


_raw: 2026-01-10 02:43:57,407 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu previously ran


_raw: 2026-01-10 02:43:57,407 - helpers.py[DEBUG]: config-byobu already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,407 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance


_raw: 2026-01-10 02:43:57,407 - 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-10 02:43:57,407 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd previously ran


_raw: 2026-01-10 02:43:57,407 - helpers.py[DEBUG]: config-runcmd already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,407 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance


_raw: 2026-01-10 02:43:57,407 - 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-10 02:43:57,407 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure previously ran


_raw: 2026-01-10 02:43:57,407 - helpers.py[DEBUG]: config-apt-configure already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,407 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance


_raw: 2026-01-10 02:43:57,407 - 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-10 02:43:57,407 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords previously ran


_raw: 2026-01-10 02:43:57,406 - helpers.py[DEBUG]: config-set-passwords already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,406 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance


_raw: 2026-01-10 02:43:57,406 - 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-10 02:43:57,406 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale previously ran


_raw: 2026-01-10 02:43:57,406 - helpers.py[DEBUG]: config-locale already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,406 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance


_raw: 2026-01-10 02:43:57,406 - 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-10 02:43:57,406 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id previously ran


_raw: 2026-01-10 02:43:57,406 - helpers.py[DEBUG]: config-ssh-import-id already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:57,406 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance


_raw: 2026-01-10 02:43:57,406 - 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-10 02:43:57,405 - 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-10 02:43:57,404 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


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


_raw: 2026-01-10 02:43:57,390 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:57,390 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:57,390 - main.py[INFO]: PID [1] started cloud-init 'modules:config'.


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


_raw: 2026-01-10 02:43:57,389 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:57,389 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:57,389 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:57,388 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:57,388 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,387 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:57,387 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,386 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:57,386 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:57,381 - 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-10 02:43:57,381 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-10 02:43:57,381 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-10 02:43:57,381 - handlers.py[DEBUG]: start: modules-config/check-cache: attempting to read from cache [trust]


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


_raw: 2026-01-10 02:43:57,380 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:57,379 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:57,379 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:57,379 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:57,379 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,377 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:57,377 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,376 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:57,376 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,372 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:57,372 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:57,372 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-10 02:43:57,372 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


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


_raw: 2026-01-10 02:43:57,372 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpdxgej3h6) - w: [644] 546 bytes/chars


_raw: 2026-01-10 02:43:57,371 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-10 02:43:57,371 - util.py[DEBUG]: Reading 533 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-10 02:43:57,371 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


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


_raw: 2026-01-10 02:43:57,370 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:57,370 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:57,369 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:57,369 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:57,369 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,368 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:57,368 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,366 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:57,366 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:57,362 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:57,361 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:57,361 - handlers.py[DEBUG]: start: modules-config: running modules for config


_raw: 2026-01-10 02:43:57,361 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7fc2fb786340>] - previously custom handler.


_raw: 2026-01-10 02:43:57,361 - socket.py[INFO]: Sending sd_notify(STATUS=Running (config stage))


_raw: 2026-01-10 02:43:57,361 - performance.py[DEBUG]: Waiting to start stage config took 0.406 seconds


_raw: 2026-01-10 02:43:56,955 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the config stage.)


_raw: 2026-01-10 02:43:56,955 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources


_raw: 2026-01-10 02:43:56,955 - performance.py[DEBUG]: cloud-init stage: 'init-network' took 0.171 seconds


_raw: 2026-01-10 02:43:56,954 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpv9abke5v) - w: [644] 533 bytes/chars


_raw: 2026-01-10 02:43:56,954 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-10 02:43:56,954 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-10 02:43:56,954 - main.py[DEBUG]: Ran 8 modules with 0 failures


_raw: 2026-01-10 02:43:56,954 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh previously ran


_raw: 2026-01-10 02:43:56,954 - helpers.py[DEBUG]: config-ssh already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,954 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance


_raw: 2026-01-10 02:43:56,954 - 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-10 02:43:56,954 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups previously ran


_raw: 2026-01-10 02:43:56,954 - helpers.py[DEBUG]: config-users-groups already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,954 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance


_raw: 2026-01-10 02:43:56,954 - 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-10 02:43:56,954 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully and took 0.001 seconds


_raw: 2026-01-10 02:43:56,954 - distros[INFO]: /var/lib/cloud/data/previous-hostname differs from /etc/hostname, assuming user maintained hostname.


_raw: 2026-01-10 02:43:56,954 - util.py[DEBUG]: Reading 28 bytes from /etc/hostname


_raw: 2026-01-10 02:43:56,954 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False)


_raw: 2026-01-10 02:43:56,954 - util.py[DEBUG]: Reading 5 bytes from /var/lib/cloud/data/previous-hostname


_raw: 2026-01-10 02:43:56,953 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False)


_raw: 2026-01-10 02:43:56,953 - cc_update_hostname.py[DEBUG]: Updating hostname to cptc11-finals-team-01-kali05-345d9702.novalocal (kali)


_raw: 2026-01-10 02:43:56,953 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7fc2fb0aec10>)


_raw: 2026-01-10 02:43:56,953 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always


_raw: 2026-01-10 02:43:56,953 - 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-10 02:43:56,953 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname previously ran


_raw: 2026-01-10 02:43:56,952 - helpers.py[DEBUG]: config-set_hostname already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,952 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance


_raw: 2026-01-10 02:43:56,952 - 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-10 02:43:56,952 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts previously ran


_raw: 2026-01-10 02:43:56,952 - helpers.py[DEBUG]: config-mounts already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,952 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance


_raw: 2026-01-10 02:43:56,952 - 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-10 02:43:56,952 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully and took 0.032 seconds


_raw: 2026-01-10 02:43:56,952 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)


_raw: 2026-01-10 02:43:56,952 - performance.py[DEBUG]: Running ('resize2fs', '/dev/vda1') took 0.031 seconds


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


_raw: 2026-01-10 02:43:56,920 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/vda1


_raw: 2026-01-10 02:43:56,920 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/vda1 mnt_point=/ path=/


_raw: 2026-01-10 02:43:56,920 - util.py[DEBUG]: Reading 2225 bytes from /proc/396/mountinfo


_raw: 2026-01-10 02:43:56,920 - util.py[DEBUG]: Reading from /proc/396/mountinfo (quiet=False)


_raw: 2026-01-10 02:43:56,920 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7fc2fb0aee90>)


_raw: 2026-01-10 02:43:56,920 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always


_raw: 2026-01-10 02:43:56,920 - 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-10 02:43:56,920 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully and took 0.053 seconds


_raw: 2026-01-10 02:43:56,920 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/vda, 1)


_raw: 2026-01-10 02:43:56,920 - performance.py[DEBUG]: Resizing devices took 0.050 seconds


_raw: 2026-01-10 02:43:56,920 - performance.py[DEBUG]: Running ['growpart', '--dry-run', '/dev/vda', '1'] took 0.046 seconds


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


_raw: 2026-01-10 02:43:56,871 - util.py[DEBUG]: Reading 2225 bytes from /proc/396/mountinfo


_raw: 2026-01-10 02:43:56,871 - util.py[DEBUG]: Reading from /proc/396/mountinfo (quiet=False)


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading 6 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev (quiet=False)


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading 2 bytes from /sys/class/block/vda1/partition


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)


_raw: 2026-01-10 02:43:56,870 - cc_growpart.py[DEBUG]: growpart found fs=ext4


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading 2225 bytes from /proc/396/mountinfo


_raw: 2026-01-10 02:43:56,870 - util.py[DEBUG]: Reading from /proc/396/mountinfo (quiet=False)


_raw: 2026-01-10 02:43:56,867 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:56,867 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg.  Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False}


_raw: 2026-01-10 02:43:56,867 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7fc2fb187b60>)


_raw: 2026-01-10 02:43:56,867 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always


_raw: 2026-01-10 02:43:56,867 - 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-10 02:43:56,867 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random previously ran


_raw: 2026-01-10 02:43:56,867 - helpers.py[DEBUG]: config-seed_random already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,867 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance


_raw: 2026-01-10 02:43:56,867 - 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-10 02:43:56,866 - 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-10 02:43:56,866 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-10 02:43:56,854 - main.py[DEBUG]: no di_report found in config.


_raw: 2026-01-10 02:43:56,854 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource


_raw: 2026-01-10 02:43:56,854 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10566 bytes


_raw: 2026-01-10 02:43:56,853 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource


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


_raw: 2026-01-10 02:43:56,851 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,851 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,847 - util.py[DEBUG]: Reading 157773 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json


_raw: 2026-01-10 02:43:56,844 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)


_raw: 2026-01-10 02:43:56,843 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,843 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,843 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmplf2yj8qf) - w: [600] 12636 bytes/chars


_raw: 2026-01-10 02:43:56,842 - util.py[DEBUG]: Reading 11479 bytes from /run/cloud-init/instance-data-sensitive.json


_raw: 2026-01-10 02:43:56,842 - util.py[DEBUG]: Reading from /run/cloud-init/instance-data-sensitive.json (quiet=False)


_raw: 2026-01-10 02:43:56,842 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/combined-cloud-config.json (via temporary file /run/cloud-init/tmpyeths3cb) - w: [600] 4960 bytes/chars


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


_raw: 2026-01-10 02:43:56,841 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,841 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,841 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:56,840 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:56,840 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,839 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:56,839 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,838 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:56,838 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,833 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:56,833 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:56,833 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2


_raw: 2026-01-10 02:43:56,833 - stages.py[DEBUG]: no vendordata2 from datasource


_raw: 2026-01-10 02:43:56,833 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2


_raw: 2026-01-10 02:43:56,833 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data


_raw: 2026-01-10 02:43:56,833 - stages.py[DEBUG]: no vendordata from datasource


_raw: 2026-01-10 02:43:56,833 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data


_raw: 2026-01-10 02:43:56,833 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency always


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,833 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,832 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/cloud-config.txt - wb: [600] 447 bytes


_raw: 2026-01-10 02:43:56,831 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency always


_raw: 2026-01-10 02:43:56,830 - util.py[DEBUG]: Attempting to load yaml from string of length 383 with allowed root types (<class 'dict'>,)


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'}


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency always


_raw: 2026-01-10 02:43:56,830 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency always


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']]


_raw: 2026-01-10 02:43:56,830 - stages.py[DEBUG]: Added default handler for {'text/cloud-config', 'text/cloud-config-jsonp'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]


_raw: 2026-01-10 02:43:56,829 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data


_raw: 2026-01-10 02:43:56,829 - helpers.py[DEBUG]: consume_data already ran (freq=once-per-instance)


_raw: 2026-01-10 02:43:56,828 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname


_raw: 2026-01-10 02:43:56,828 - util.py[DEBUG]: Reading 84 bytes from /var/lib/cloud/data/set-hostname


_raw: 2026-01-10 02:43:56,828 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False)


_raw: 2026-01-10 02:43:56,827 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/vendor-data2.txt.i - wb: [600] 308 bytes


_raw: 2026-01-10 02:43:56,827 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/vendor-data2.txt - wb: [600] 0 bytes


_raw: 2026-01-10 02:43:56,827 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/vendor-data.txt.i - wb: [600] 308 bytes


_raw: 2026-01-10 02:43:56,826 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/vendor-data.txt - wb: [600] 0 bytes


_raw: 2026-01-10 02:43:56,826 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/user-data.txt.i - wb: [600] 688 bytes


_raw: 2026-01-10 02:43:56,826 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/user-data.txt - wb: [600] 383 bytes


_raw: 2026-01-10 02:43:56,826 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource


_raw: 2026-01-10 02:43:56,826 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource


_raw: 2026-01-10 02:43:56,824 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:56,823 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:56,823 - util.py[DEBUG]: Reading 3 bytes from /sys/class/net/eth0/operstate


_raw: 2026-01-10 02:43:56,823 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)


_raw: 2026-01-10 02:43:56,823 - util.py[DEBUG]: Reading 8 bytes from /sys/class/net/lo/operstate


_raw: 2026-01-10 02:43:56,823 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/eth0/addr_assign_type


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 25 bytes from /sys/class/net/eth0/uevent


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/lo/addr_assign_type


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading 23 bytes from /sys/class/net/lo/uevent


_raw: 2026-01-10 02:43:56,822 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)


_raw: 2026-01-10 02:43:56,822 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-10 02:43:56,821 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'mtu': None, 'subnets': [{'type': 'dhcp4'}], 'mac_address': 'fa:16:3e:59:93:71', 'name': 'eth0'}, {'type': 'nameserver', 'address': '1.1.1.1'}]}


_raw: 2026-01-10 02:43:56,821 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed


_raw: 2026-01-10 02:43:56,821 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy


_raw: 2026-01-10 02:43:56,821 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}}


_raw: 2026-01-10 02:43:56,821 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled


_raw: 2026-01-10 02:43:56,821 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False)


_raw: 2026-01-10 02:43:56,821 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot


_raw: 2026-01-10 02:43:56,821 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-10 02:43:56,821 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-10 02:43:56,821 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-10 02:43:56,820 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-10 02:43:56,820 - main.py[DEBUG]: [net] init will now be targeting instance id: a228eb25-45ab-47aa-8012-07fbd68e16c1. new=False


_raw: 2026-01-10 02:43:56,820 - stages.py[INFO]: Not re-loading configuration, instance id and datasource have not changed.


_raw: 2026-01-10 02:43:56,820 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10575 bytes


_raw: 2026-01-10 02:43:56,820 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:56,819 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:56,819 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:56,819 - util.py[DEBUG]: Reading 37 bytes from /var/lib/cloud/data/instance-id


_raw: 2026-01-10 02:43:56,819 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)


_raw: 2026-01-10 02:43:56,819 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 74 bytes


_raw: 2026-01-10 02:43:56,818 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource - wb: [644] 74 bytes


_raw: 2026-01-10 02:43:56,818 - util.py[DEBUG]: Reading 74 bytes from /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource


_raw: 2026-01-10 02:43:56,818 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource (quiet=False)


_raw: 2026-01-10 02:43:56,818 - stages.py[INFO]: Instance link already exists, not recreating it.


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


_raw: 2026-01-10 02:43:56,817 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,817 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,817 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:56,817 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:56,817 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,815 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:56,815 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,814 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:56,814 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,810 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:56,810 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:56,810 - 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-10 02:43:56,810 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-10 02:43:56,810 - util.py[DEBUG]: Reading 37 bytes from /run/cloud-init/.instance-id


_raw: 2026-01-10 02:43:56,810 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)


_raw: 2026-01-10 02:43:56,809 - util.py[DEBUG]: Reading 10575 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-10 02:43:56,809 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-10 02:43:56,809 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]


_raw: 2026-01-10 02:43:56,807 - 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-10 02:43:56,805 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:56,803 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:56,803 - util.py[DEBUG]: Reading 4 bytes from /var/lib/cloud/data/python-version


_raw: 2026-01-10 02:43:56,803 - util.py[DEBUG]: Reading from /var/lib/cloud/data/python-version (quiet=False)


_raw: 2026-01-10 02:43:56,803 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4


_raw: 2026-01-10 02:43:56,803 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes


_raw: 2026-01-10 02:43:56,802 - main.py[DEBUG]: No kernel command line url found.


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


_raw: 2026-01-10 02:43:56,800 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,800 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,800 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:56,800 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:56,800 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,799 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:56,798 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,797 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:56,797 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,794 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:56,794 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:56,793 - util.py[DEBUG]: Reading 12 bytes from /proc/uptime


_raw: 2026-01-10 02:43:56,793 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


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


_raw: 2026-01-10 02:43:56,793 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpq55df11x) - w: [644] 534 bytes/chars


_raw: 2026-01-10 02:43:56,793 - util.py[DEBUG]: Reading 531 bytes from /var/lib/cloud/data/status.json


_raw: 2026-01-10 02:43:56,793 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)


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


_raw: 2026-01-10 02:43:56,791 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:56,791 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:56,791 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:56,791 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:56,791 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,789 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:56,789 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,788 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:56,788 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:56,784 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:56,784 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:56,784 - handlers.py[DEBUG]: start: init-network: searching for network datasources


_raw: 2026-01-10 02:43:56,784 - signal_handler.py[INFO]: Signal state [<function _handle_exit at 0x7fc2fb786340>] - previously custom handler.


_raw: 2026-01-10 02:43:56,783 - socket.py[INFO]: Sending sd_notify(STATUS=Running (network stage))


_raw: 2026-01-10 02:43:56,783 - performance.py[DEBUG]: Waiting to start stage network took 7.351 seconds


_raw: 2026-01-10 02:43:49,432 - socket.py[INFO]: Sending sd_notify(STATUS=Waiting on external services to complete before starting the network stage.)


_raw: 2026-01-10 02:43:49,432 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources


_raw: 2026-01-10 02:43:49,432 - performance.py[DEBUG]: cloud-init stage: 'init-local' took 0.077 seconds


_raw: 2026-01-10 02:43:49,432 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp94sjvnkd) - w: [644] 531 bytes/chars


_raw: 2026-01-10 02:43:49,432 - util.py[DEBUG]: Reading 11 bytes from /proc/uptime


_raw: 2026-01-10 02:43:49,432 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)


_raw: 2026-01-10 02:43:49,432 - main.py[DEBUG]: [local] Exiting. datasource DataSourceConfigDrive [net,ver=2][source=/dev/sr0] not in local mode.


_raw: 2026-01-10 02:43:49,431 - util.py[DEBUG]: Writing to /run/cloud-init/.skip-network - wb: [644] 0 bytes


_raw: 2026-01-10 02:43:49,431 - 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-10 02:43:49,428 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:49,422 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 5 bytes from /sys/class/net/eth0/operstate


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 8 bytes from /sys/class/net/lo/operstate


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/eth0/addr_assign_type


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading 25 bytes from /sys/class/net/eth0/uevent


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)


_raw: 2026-01-10 02:43:49,422 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)


_raw: 2026-01-10 02:43:49,422 - net[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading 2 bytes from /sys/class/net/lo/addr_assign_type


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading 23 bytes from /sys/class/net/lo/uevent


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading 7 bytes from /sys/class/net/eth0/device/device


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)


_raw: 2026-01-10 02:43:49,421 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'type': 'physical', 'mtu': None, 'subnets': [{'type': 'dhcp4'}], 'mac_address': 'fa:16:3e:59:93:71', 'name': 'eth0'}, {'type': 'nameserver', 'address': '1.1.1.1'}]}


_raw: 2026-01-10 02:43:49,421 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed


_raw: 2026-01-10 02:43:49,421 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy


_raw: 2026-01-10 02:43:49,421 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}}


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled


_raw: 2026-01-10 02:43:49,421 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False)


_raw: 2026-01-10 02:43:49,421 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot


_raw: 2026-01-10 02:43:49,420 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/eth0/address


_raw: 2026-01-10 02:43:49,420 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)


_raw: 2026-01-10 02:43:49,420 - util.py[DEBUG]: Reading 18 bytes from /sys/class/net/lo/address


_raw: 2026-01-10 02:43:49,420 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)


_raw: 2026-01-10 02:43:49,420 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname


_raw: 2026-01-10 02:43:49,420 - util.py[DEBUG]: Reading 84 bytes from /var/lib/cloud/data/set-hostname


_raw: 2026-01-10 02:43:49,419 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False)


_raw: 2026-01-10 02:43:49,418 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>


_raw: 2026-01-10 02:43:49,418 - main.py[DEBUG]: [local] init will now be targeting instance id: a228eb25-45ab-47aa-8012-07fbd68e16c1. new=False


_raw: 2026-01-10 02:43:49,418 - stages.py[INFO]: Not re-loading configuration, instance id and datasource have not changed.


_raw: 2026-01-10 02:43:49,418 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10575 bytes


_raw: 2026-01-10 02:43:49,417 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:49,417 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:49,417 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes


_raw: 2026-01-10 02:43:49,417 - util.py[DEBUG]: Reading 37 bytes from /var/lib/cloud/data/instance-id


_raw: 2026-01-10 02:43:49,416 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)


_raw: 2026-01-10 02:43:49,416 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 74 bytes


_raw: 2026-01-10 02:43:49,415 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource - wb: [644] 74 bytes


_raw: 2026-01-10 02:43:49,415 - util.py[DEBUG]: Reading 74 bytes from /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource


_raw: 2026-01-10 02:43:49,414 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/a228eb25-45ab-47aa-8012-07fbd68e16c1/datasource (quiet=False)


_raw: 2026-01-10 02:43:49,414 - stages.py[INFO]: Instance link already exists, not recreating it.


_raw: 2026-01-10 02:43:49,414 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpd3m4_5hq) - w: [644] 4014 bytes/chars


_raw: 2026-01-10 02:43:49,413 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmphl6_kl3n) - w: [600] 11479 bytes/chars


_raw: 2026-01-10 02:43:49,413 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-configdrive'


_raw: 2026-01-10 02:43:49,413 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-configdrive - wb: [644] 12 bytes


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


_raw: 2026-01-10 02:43:49,411 - util.py[DEBUG]: Reading 447 bytes from /var/lib/cloud/instance/cloud-config.txt


_raw: 2026-01-10 02:43:49,411 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)


_raw: 2026-01-10 02:43:49,410 - util.py[DEBUG]: loaded blob returned None, returning default.


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


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


_raw: 2026-01-10 02:43:49,410 - util.py[DEBUG]: Reading 39 bytes from /run/cloud-init/cloud.cfg


_raw: 2026-01-10 02:43:49,410 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:49,409 - util.py[DEBUG]: Reading 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg


_raw: 2026-01-10 02:43:49,409 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:49,407 - util.py[DEBUG]: Reading 513 bytes from /etc/cloud/cloud.cfg.d/20_kali.cfg


_raw: 2026-01-10 02:43:49,407 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/20_kali.cfg (quiet=False)


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


_raw: 2026-01-10 02:43:49,403 - util.py[DEBUG]: Reading 2729 bytes from /etc/cloud/cloud.cfg


_raw: 2026-01-10 02:43:49,403 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)


_raw: 2026-01-10 02:43:49,403 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: restored from checked cache: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-10 02:43:49,403 - stages.py[DEBUG]: restored from checked cache: DataSourceConfigDrive [net,ver=2][source=/dev/sr0]


_raw: 2026-01-10 02:43:49,402 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_uuid


_raw: 2026-01-10 02:43:49,393 - util.py[DEBUG]: Reading 10566 bytes from /var/lib/cloud/instance/obj.pkl


_raw: 2026-01-10 02:43:49,393 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)


_raw: 2026-01-10 02:43:49,393 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check]


_raw: 2026-01-10 02:43:49,393 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished


_raw: 2026-01-10 02:43:49,393 - util.py[DEBUG]: Reading 4 bytes from /var/lib/cloud/data/python-version


_raw: 2026-01-10 02:43:49,392 - util.py[DEBUG]: Reading from /var/lib/cloud/data/python-version (quiet=False)


_raw: 2026-01-10 02:43:49,392 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4


_raw: 2026-01-10 02:43:49,391 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes


_raw: 2026-01-10 02:43:49,389 - main.py[DEBUG]: No kernel command line url found.


_raw: 2026-01-10 02:43:49,389 - main.py[INFO]: PID [1] started cloud-init 'init-local'.


_raw: 2026-01-10 02:43:09.019 EST [318457] LOG:  checkpoint complete: wrote 6 buffers (0.0%), wrote 1 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.603 s, sync=0.016 s, total=0.630 s; sync files=5, longest=0.014 s, average=0.004 s; distance=44 kB, estimate=44 kB; lsn=0/20F8BA0, redo lsn=0/20F8B48


_raw: (lightdm-gtk-greeter:1165): Gtk-WARNING **: 02:43:00.996: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:43:00.983: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.970: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:43:00.933: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.921: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:928): Gtk-WARNING **: 02:43:00.917: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:43:00.907: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.905: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.898: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:969): Gtk-WARNING **: 02:43:00.880: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.870: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.831: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:949): Gtk-WARNING **: 02:43:00.830: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:961): Gtk-WARNING **: 02:43:00.822: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:43:00.765: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.747: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.726: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:43:00.720: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:43:00.691: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:43:00.678: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:43:00.673: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.646: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.645: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.642: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.642: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:943): Gtk-WARNING **: 02:43:00.633: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.624: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:43:00.612: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:43:00.608: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1158): Gtk-WARNING **: 02:43:00.603: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:934): Gtk-WARNING **: 02:43:00.602: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:968): Gtk-WARNING **: 02:43:00.592: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.583: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:43:00.571: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:941): Gtk-WARNING **: 02:43:00.564: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:952): Gtk-WARNING **: 02:43:00.531: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:43:00.528: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:43:00.523: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:961): Gtk-WARNING **: 02:43:00.515: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:941): Gtk-WARNING **: 02:43:00.511: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.469: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1319): Gtk-WARNING **: 02:43:00.457: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.445: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:43:00.440: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:43:00.435: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1153): Gtk-WARNING **: 02:43:00.434: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:43:00.430: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:945): Gtk-WARNING **: 02:43:00.413: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:959): Gtk-WARNING **: 02:43:00.364: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:948): Gtk-WARNING **: 02:43:00.315: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.299: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:946): Gtk-WARNING **: 02:43:00.291: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:946): Gtk-WARNING **: 02:43:00.275: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:43:00.269: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.268: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:943): Gtk-WARNING **: 02:43:00.241: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:932): Gtk-WARNING **: 02:43:00.196: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:946): Gtk-WARNING **: 02:43:00.187: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:43:00.156: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:943): Gtk-WARNING **: 02:43:00.150: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.143: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1044): Gtk-WARNING **: 02:43:00.140: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:948): Gtk-WARNING **: 02:43:00.135: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:933): Gtk-WARNING **: 02:43:00.123: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:43:00.116: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:43:00.107: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:943): Gtk-WARNING **: 02:43:00.105: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:971): Gtk-WARNING **: 02:43:00.084: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:43:00.045: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:43:00.033: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:934): Gtk-WARNING **: 02:43:00.030: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:43:00.028: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1151): Gtk-WARNING **: 02:43:00.021: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1149): Gtk-WARNING **: 02:43:00.008: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:42:00.987: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:42:00.971: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:934): Gtk-WARNING **: 02:42:00.968: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:42:00.967: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1151): Gtk-WARNING **: 02:42:00.957: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1149): Gtk-WARNING **: 02:42:00.945: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1165): Gtk-WARNING **: 02:42:00.931: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:42:00.919: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.912: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:42:00.872: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:42:00.858: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:928): Gtk-WARNING **: 02:42:00.853: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:938): Gtk-WARNING **: 02:42:00.848: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.845: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.835: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:969): Gtk-WARNING **: 02:42:00.817: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.805: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:42:00.780: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:949): Gtk-WARNING **: 02:42:00.771: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:961): Gtk-WARNING **: 02:42:00.764: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:42:00.704: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:42:00.689: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.668: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:42:00.658: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:42:00.632: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:42:00.620: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:42:00.617: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:42:00.590: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.583: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.582: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:940): Gtk-WARNING **: 02:42:00.578: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:943): Gtk-WARNING **: 02:42:00.570: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.561: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:42:00.548: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:42:00.546: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:934): Gtk-WARNING **: 02:42:00.539: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1158): Gtk-WARNING **: 02:42:00.539: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:968): Gtk-WARNING **: 02:42:00.533: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:939): Gtk-WARNING **: 02:42:00.517: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:42:00.516: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:941): Gtk-WARNING **: 02:42:00.503: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:952): Gtk-WARNING **: 02:42:00.470: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:935): Gtk-WARNING **: 02:42:00.466: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:942): Gtk-WARNING **: 02:42:00.457: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:941): Gtk-WARNING **: 02:42:00.455: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:961): Gtk-WARNING **: 02:42:00.451: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.407: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1319): Gtk-WARNING **: 02:42:00.395: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.387: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:944): Gtk-WARNING **: 02:42:00.377: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:936): Gtk-WARNING **: 02:42:00.377: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:1153): Gtk-WARNING **: 02:42:00.372: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:937): Gtk-WARNING **: 02:42:00.369: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:945): Gtk-WARNING **: 02:42:00.352: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:959): Gtk-WARNING **: 02:42:00.303: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)


_raw: (lightdm-gtk-greeter:948): Gtk-WARNING **: 02:42:00.254: Drawing a gadget with negative dimensions. Did you forget to allocate a size? (node menubar owner GreeterMenuBar)