# Pastebin fPXcz5bB
2023-10-19 08:31:22,606 - util.py[DEBUG]: Cloud-init v. 22.4-28-gaecdcbf8 running 'init' at Thu, 19 Oct 2023 08:31:22 +0000. Up 1145.9711694717407 seconds.
2023-10-19 08:31:22,606 - main.py[DEBUG]: No kernel command line url found.
2023-10-19 08:31:22,606 - main.py[DEBUG]: Closing stdin.
2023-10-19 08:31:22,609 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes
2023-10-19 08:31:22,609 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
2023-10-19 08:31:22,609 - util.py[DEBUG]: Writing to /var/lib/cloud/data/python-version - wb: [644] 3 bytes
2023-10-19 08:31:22,610 - subp.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0, 1] (shell=False, capture=True)
2023-10-19 08:31:22,642 - subp.py[DEBUG]: Running command ['netstat', '--route', '--numeric', '--extend'] with allowed return codes [0, 1] (shell=False, capture=True)
2023-10-19 08:31:22,643 - subp.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '--route', '--numeric'] with allowed return codes [0, 1] (shell=False, capture=True)
2023-10-19 08:31:22,645 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
2023-10-19 08:31:22,645 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2023-10-19 08:31:22,645 - stages.py[DEBUG]: no cache found
2023-10-19 08:31:22,645 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found
2023-10-19 08:31:22,645 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2023-10-19 08:31:22,646 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2023-10-19 08:31:22,646 - subp.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,678 - __init__.py[DEBUG]: Looking for data source in: ['Ec2'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
2023-10-19 08:31:22,679 - __init__.py[DEBUG]: Searching for network data source in: ['DataSourceEc2']
2023-10-19 08:31:22,679 - handlers.py[DEBUG]: start: init-network/search-Ec2: searching for network data from DataSourceEc2
2023-10-19 08:31:22,679 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceEc2.DataSourceEc2'>
2023-10-19 08:31:22,679 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: boot-new-instance
2023-10-19 08:31:22,680 - util.py[DEBUG]: Reading from /sys/hypervisor/uuid (quiet=False)
2023-10-19 08:31:22,680 - dmi.py[DEBUG]: querying dmi data smbios.system.uuid
2023-10-19 08:31:22,680 - subp.py[DEBUG]: Running command ['kenv', '-q', 'smbios.system.uuid'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,681 - dmi.py[DEBUG]: kenv returned '13440600-b7ed-11ec-8000-3cecefccceb4' for 'smbios.system.uuid'
2023-10-19 08:31:22,681 - dmi.py[DEBUG]: querying dmi data smbios.system.serial
2023-10-19 08:31:22,681 - subp.py[DEBUG]: Running command ['kenv', '-q', 'smbios.system.serial'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,682 - dmi.py[DEBUG]: kenv returned 'E496457X2500611' for 'smbios.system.serial'
2023-10-19 08:31:22,682 - dmi.py[DEBUG]: querying dmi data smbios.chassis.tag
2023-10-19 08:31:22,682 - subp.py[DEBUG]: Running command ['kenv', '-q', 'smbios.chassis.tag'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,683 - dmi.py[DEBUG]: kenv returned 'To be filled by O.E.M.' for 'smbios.chassis.tag'
2023-10-19 08:31:22,683 - dmi.py[DEBUG]: querying dmi data smbios.system.maker
2023-10-19 08:31:22,683 - subp.py[DEBUG]: Running command ['kenv', '-q', 'smbios.system.maker'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,684 - dmi.py[DEBUG]: kenv returned 'Supermicro' for 'smbios.system.maker'
2023-10-19 08:31:22,684 - dmi.py[DEBUG]: querying dmi data smbios.system.product
2023-10-19 08:31:22,684 - subp.py[DEBUG]: Running command ['kenv', '-q', 'smbios.system.product'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,685 - dmi.py[DEBUG]: kenv returned 'SYS-510T-MR1-EI018' for 'smbios.system.product'
2023-10-19 08:31:22,686 - DataSourceEc2.py[DEBUG]: strict_mode: false, cloud_name=unknown cloud_platform=ec2
2023-10-19 08:31:22,785 - util.py[DEBUG]: Resolving URL: https://metadata.packet.net took 0.099 seconds
2023-10-19 08:31:22,787 - util.py[DEBUG]: Resolving URL: http://metadata.packet.net took 0.002 seconds
2023-10-19 08:31:22,787 - util.py[DEBUG]: Resolving URL: http://147.75.207.1 took 0.000 seconds
2023-10-19 08:31:22,787 - url_helper.py[DEBUG]: [0/1] open 'https://metadata.packet.net/2009-04-04/meta-data/instance-id' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 60.0} configuration
2023-10-19 08:31:22,805 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/instance-id (200, 36b) after 1 attempts
2023-10-19 08:31:22,805 - DataSourceEc2.py[DEBUG]: Using metadata source: 'https://metadata.packet.net'
2023-10-19 08:31:22,805 - url_helper.py[DEBUG]: [0/1] open 'https://metadata.packet.net/2021-03-23/meta-data/instance-id' with {'url': 'https://metadata.packet.net/2021-03-23/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET'} configuration
2023-10-19 08:31:22,815 - DataSourceEc2.py[DEBUG]: url https://metadata.packet.net/2021-03-23/meta-data/instance-id raised exception 500 Server Error: Internal Server Error for url: https://metadata.packet.net/2021-03-23/meta-data/instance-id
2023-10-19 08:31:22,815 - url_helper.py[DEBUG]: [0/1] open 'https://metadata.packet.net/2018-09-24/meta-data/instance-id' with {'url': 'https://metadata.packet.net/2018-09-24/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET'} configuration
2023-10-19 08:31:22,823 - DataSourceEc2.py[DEBUG]: url https://metadata.packet.net/2018-09-24/meta-data/instance-id raised exception 500 Server Error: Internal Server Error for url: https://metadata.packet.net/2018-09-24/meta-data/instance-id
2023-10-19 08:31:22,823 - url_helper.py[DEBUG]: [0/1] open 'https://metadata.packet.net/2016-09-02/meta-data/instance-id' with {'url': 'https://metadata.packet.net/2016-09-02/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET'} configuration
2023-10-19 08:31:22,832 - DataSourceEc2.py[DEBUG]: url https://metadata.packet.net/2016-09-02/meta-data/instance-id raised exception 500 Server Error: Internal Server Error for url: https://metadata.packet.net/2016-09-02/meta-data/instance-id
2023-10-19 08:31:22,832 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/user-data' with {'url': 'https://metadata.packet.net/2009-04-04/user-data', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,842 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/user-data (200, 1028b) after 1 attempts
2023-10-19 08:31:22,843 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,853 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/ (200, 107b) after 1 attempts
2023-10-19 08:31:22,853 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/instance-id' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,863 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/instance-id (200, 36b) after 1 attempts
2023-10-19 08:31:22,863 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/hostname' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,874 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/hostname (200, 14b) after 1 attempts
2023-10-19 08:31:22,874 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/iqn' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/iqn', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,885 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/iqn (200, 38b) after 1 attempts
2023-10-19 08:31:22,885 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/plan' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/plan', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,896 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/plan (200, 12b) after 1 attempts
2023-10-19 08:31:22,896 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/facility' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/facility', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,907 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/facility (200, 3b) after 1 attempts
2023-10-19 08:31:22,907 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/tags' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/tags', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,917 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/tags (200, 0b) after 1 attempts
2023-10-19 08:31:22,918 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/operating-system' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/operating-system', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,928 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/operating-system (200, 48b) after 1 attempts
2023-10-19 08:31:22,928 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/public-keys' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/public-keys', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,939 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/public-keys (200, 690b) after 1 attempts
2023-10-19 08:31:22,939 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/public-ipv4' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/public-ipv4', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,950 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/public-ipv4 (200, 13b) after 1 attempts
2023-10-19 08:31:22,950 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/public-ipv6' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/public-ipv6', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,960 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/public-ipv6 (200, 22b) after 1 attempts
2023-10-19 08:31:22,961 - url_helper.py[DEBUG]: [0/6] open 'https://metadata.packet.net/2009-04-04/meta-data/local-ipv4' with {'url': 'https://metadata.packet.net/2009-04-04/meta-data/local-ipv4', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2023-10-19 08:31:22,971 - url_helper.py[DEBUG]: Read from https://metadata.packet.net/2009-04-04/meta-data/local-ipv4 (200, 13b) after 1 attempts
2023-10-19 08:31:22,971 - util.py[DEBUG]: Crawl of metadata service took 0.285 seconds
2023-10-19 08:31:22,971 - util.py[DEBUG]: Reading from /etc/hosts (quiet=False)
2023-10-19 08:31:22,971 - util.py[DEBUG]: Read 1031 bytes from /etc/hosts
2023-10-19 08:31:22,972 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-ec2 - wb: [644] 4 bytes
2023-10-19 08:31:22,972 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-ec2'
2023-10-19 08:31:22,972 - util.py[DEBUG]: Attempting to remove /run/cloud-init/cloud-id-ec2
2023-10-19 08:31:22,973 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmp6vzkf3ka) - w: [600] 6137 bytes/chars
2023-10-19 08:31:22,974 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmp8ffea6ca) - w: [644] 3326 bytes/chars
2023-10-19 08:31:22,974 - handlers.py[DEBUG]: finish: init-network/search-Ec2: SUCCESS: found network data from DataSourceEc2
2023-10-19 08:31:22,974 - stages.py[INFO]: Loaded datasource DataSourceEc2 - DataSourceEc2
2023-10-19 08:31:22,974 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg (quiet=False)
2023-10-19 08:31:22,974 - util.py[DEBUG]: Read 703 bytes from /usr/local/etc/cloud/cloud.cfg
2023-10-19 08:31:22,974 - util.py[DEBUG]: Attempting to load yaml from string of length 703 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,976 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg (quiet=False)
2023-10-19 08:31:22,976 - util.py[DEBUG]: Read 59 bytes from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg
2023-10-19 08:31:22,977 - util.py[DEBUG]: Attempting to load yaml from string of length 59 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,977 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2023-10-19 08:31:22,977 - util.py[DEBUG]: Read 2070 bytes from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg
2023-10-19 08:31:22,977 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,979 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)
2023-10-19 08:31:22,979 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,979 - util.py[DEBUG]: loaded blob returned None, returning default.
2023-10-19 08:31:22,980 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2023-10-19 08:31:22,980 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33'
2023-10-19 08:31:22,981 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/datasource (quiet=False)
2023-10-19 08:31:22,981 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/datasource - wb: [644] 29 bytes
2023-10-19 08:31:22,982 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 29 bytes
2023-10-19 08:31:22,982 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
2023-10-19 08:31:22,982 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID
2023-10-19 08:31:22,982 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 37 bytes
2023-10-19 08:31:22,982 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 37 bytes
2023-10-19 08:31:22,982 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes
2023-10-19 08:31:22,983 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 8591 bytes
2023-10-19 08:31:22,983 - main.py[DEBUG]: [net] init will now be targeting instance id: bbabee48-2061-41bb-80ee-0c7fbde96f33. new=True
2023-10-19 08:31:22,984 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg (quiet=False)
2023-10-19 08:31:22,984 - util.py[DEBUG]: Read 703 bytes from /usr/local/etc/cloud/cloud.cfg
2023-10-19 08:31:22,984 - util.py[DEBUG]: Attempting to load yaml from string of length 703 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,986 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg (quiet=False)
2023-10-19 08:31:22,986 - util.py[DEBUG]: Read 59 bytes from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg
2023-10-19 08:31:22,986 - util.py[DEBUG]: Attempting to load yaml from string of length 59 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,986 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2023-10-19 08:31:22,986 - util.py[DEBUG]: Read 2070 bytes from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg
2023-10-19 08:31:22,986 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,989 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)
2023-10-19 08:31:22,989 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,989 - util.py[DEBUG]: loaded blob returned None, returning default.
2023-10-19 08:31:22,989 - subp.py[DEBUG]: Running command ['ifconfig', '-a', 'ether'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,991 - util.py[DEBUG]: Reading from /sys/class/net/igb0/address (quiet=False)
2023-10-19 08:31:22,991 - util.py[DEBUG]: Reading from /sys/class/net/igb1/address (quiet=False)
2023-10-19 08:31:22,991 - util.py[DEBUG]: Reading from /sys/class/net/lagg0/address (quiet=False)
2023-10-19 08:31:22,991 - util.py[DEBUG]: Reading from /sys/class/net/ue0/address (quiet=False)
2023-10-19 08:31:22,992 - subp.py[DEBUG]: Running command ['ifconfig', '-l', '-u', 'ether'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:22,993 - DataSourceEc2.py[WARNING]: Metadata 'network' key not valid: None.
2023-10-19 08:31:22,993 - stages.py[DEBUG]: network config disabled by system_cfg
2023-10-19 08:31:22,993 - stages.py[INFO]: network config is disabled by system_cfg
2023-10-19 08:31:22,993 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource
2023-10-19 08:31:22,993 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource
2023-10-19 08:31:22,993 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/user-data.txt - wb: [600] 1028 bytes
2023-10-19 08:31:22,994 - util.py[DEBUG]: Attempting to load yaml from string of length 1028 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:22,997 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/user-data.txt.i - wb: [600] 1333 bytes
2023-10-19 08:31:22,997 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/vendor-data.txt - wb: [600] 0 bytes
2023-10-19 08:31:22,998 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/vendor-data.txt.i - wb: [600] 308 bytes
2023-10-19 08:31:22,998 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/vendor-data2.txt - wb: [600] 0 bytes
2023-10-19 08:31:22,999 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/vendor-data2.txt.i - wb: [600] 308 bytes
2023-10-19 08:31:22,999 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2023-10-19 08:31:22,999 - subp.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:23,031 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/sem/consume_data - wb: [644] 24 bytes
2023-10-19 08:31:23,031 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/sem/consume_data'>)
2023-10-19 08:31:23,032 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']]
2023-10-19 08:31:23,032 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']]
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'}
2023-10-19 08:31:23,032 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance
2023-10-19 08:31:23,032 - util.py[DEBUG]: Attempting to load yaml from string of length 1028 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,034 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])]
2023-10-19 08:31:23,035 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
2023-10-19 08:31:23,036 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/cloud-config.txt - wb: [600] 892 bytes
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
2023-10-19 08:31:23,036 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance
2023-10-19 08:31:23,036 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data
2023-10-19 08:31:23,036 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data
2023-10-19 08:31:23,036 - stages.py[DEBUG]: no vendordata from datasource
2023-10-19 08:31:23,036 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data
2023-10-19 08:31:23,036 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2
2023-10-19 08:31:23,036 - stages.py[DEBUG]: no vendordata2 from datasource
2023-10-19 08:31:23,036 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2
2023-10-19 08:31:23,037 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg (quiet=False)
2023-10-19 08:31:23,037 - util.py[DEBUG]: Read 703 bytes from /usr/local/etc/cloud/cloud.cfg
2023-10-19 08:31:23,037 - util.py[DEBUG]: Attempting to load yaml from string of length 703 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,039 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg (quiet=False)
2023-10-19 08:31:23,039 - util.py[DEBUG]: Read 59 bytes from /usr/local/etc/cloud/cloud.cfg.d/99_freebsd.cfg
2023-10-19 08:31:23,039 - util.py[DEBUG]: Attempting to load yaml from string of length 59 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,039 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2023-10-19 08:31:23,039 - util.py[DEBUG]: Read 2070 bytes from /usr/local/etc/cloud/cloud.cfg.d/05_logging.cfg
2023-10-19 08:31:23,039 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,042 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False)
2023-10-19 08:31:23,042 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,042 - util.py[DEBUG]: loaded blob returned None, returning default.
2023-10-19 08:31:23,042 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2023-10-19 08:31:23,042 - util.py[DEBUG]: Read 892 bytes from /var/lib/cloud/instance/cloud-config.txt
2023-10-19 08:31:23,042 - util.py[DEBUG]: Attempting to load yaml from string of length 892 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,044 - util.py[DEBUG]: Reading from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)
2023-10-19 08:31:23,044 - util.py[DEBUG]: Read 133293 bytes from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json
2023-10-19 08:31:23,050 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2023-10-19 08:31:23,050 - util.py[DEBUG]: Read 892 bytes from /var/lib/cloud/instance/cloud-config.txt
2023-10-19 08:31:23,050 - util.py[DEBUG]: Attempting to load yaml from string of length 892 with allowed root types (<class 'dict'>,)
2023-10-19 08:31:23,053 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource
2023-10-19 08:31:23,053 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 11343 bytes
2023-10-19 08:31:23,054 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource
2023-10-19 08:31:23,054 - main.py[DEBUG]: no di_report found in config.
2023-10-19 08:31:23,054 - util.py[DEBUG]: Reading from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)
2023-10-19 08:31:23,054 - util.py[DEBUG]: Read 133293 bytes from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json
2023-10-19 08:31:23,055 - util.py[DEBUG]: Reading from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False)
2023-10-19 08:31:23,055 - util.py[DEBUG]: Read 133293 bytes from /usr/local/lib/python3.9/site-packages/cloudinit/config/schemas/schema-cloud-config-v1.json
2023-10-19 08:31:23,057 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2023-10-19 08:31:23,057 - subp.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
2023-10-19 08:31:23,089 - modules.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/local/lib/python3.9/site-packages/cloudinit/config/cc_migrator.py'>) with frequency always
2023-10-19 08:31:23,089 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always
2023-10-19 08:31:23,089 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x8625fdf40>)
2023-10-19 08:31:23,089 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names
2023-10-19 08:31:23,090 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully
2023-10-19 08:31:23,090 - modules.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/local/lib/python3.9/site-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance
2023-10-19 08:31:23,090 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance
2023-10-19 08:31:23,090 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/sem/config_ssh - wb: [644] 24 bytes
2023-10-19 08:31:23,090 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/bbabee48-2061-41bb-80ee-0c7fbde96f33/sem/config_ssh'>)
2023-10-19 08:31:23,090 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False)
2023-10-19 08:31:23,090 - util.py[DEBUG]: Read 567 bytes from /etc/ssh/ssh_host_rsa_key.pub
2023-10-19 08:31:23,090 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False)
2023-10-19 08:31:23,090 - util.py[DEBUG]: Read 175 bytes from /etc/ssh/ssh_host_ecdsa_key.pub
2023-10-19 08:31:23,091 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False)
2023-10-19 08:31:23,091 - util.py[DEBUG]: Read 95 bytes from /etc/ssh/ssh_host_ed25519_key.pub
2023-10-19 08:31:23,091 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
2023-10-19 08:31:23,091 - util.py[DEBUG]: Read 3396 bytes from /etc/ssh/sshd_config
2023-10-19 08:31:23,091 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False)
2023-10-19 08:31:23,091 - util.py[DEBUG]: Read 691 bytes from /root/.ssh/authorized_keys
2023-10-19 08:31:23,091 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [644] 691 bytes
2023-10-19 08:31:23,092 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully
2023-10-19 08:31:23,092 - main.py[DEBUG]: Ran 2 modules with 0 failures
2023-10-19 08:31:23,092 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp2fv0o94s) - w: [644] 486 bytes/chars
2023-10-19 08:31:23,092 - util.py[DEBUG]: cloud-init mode 'init' took 0.510 seconds (0.51)
2023-10-19 08:31:23,092 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources