2024-12-19 07:27:36,878 - util.py[DEBUG]: Cloud-init v. 24.3.1-0ubuntu0~24.04.2 running 'init-local' at Thu, 19 Dec 2024 07:27:36 +0000. Up 4.94 seconds. 2024-12-19 07:27:36,883 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:36,883 - main.py[INFO]: PID [1] started cloud-init 'init-local'. 2024-12-19 07:27:36,883 - main.py[DEBUG]: No kernel command line url found. 2024-12-19 07:27:36,883 - main.py[DEBUG]: Closing stdin 2024-12-19 07:27:36,885 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes 2024-12-19 07:27:36,885 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 102:4 2024-12-19 07:27:36,886 - util.py[DEBUG]: Writing to /var/lib/cloud/data/python-version - wb: [644] 4 bytes 2024-12-19 07:27:36,886 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished 2024-12-19 07:27:36,886 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] 2024-12-19 07:27:36,886 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2024-12-19 07:27:36,886 - stages.py[DEBUG]: no cache found 2024-12-19 07:27:36,886 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found 2024-12-19 07:27:36,886 - sources[DEBUG]: Looking for data source in: ['Ec2', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] 2024-12-19 07:27:36,906 - sources[DEBUG]: Searching for local data source in: ['DataSourceEc2Local'] 2024-12-19 07:27:36,906 - handlers.py[DEBUG]: start: init-local/search-Ec2Local: searching for local data from DataSourceEc2Local 2024-12-19 07:27:36,906 - sources[DEBUG]: Seeing if we can get any data from 2024-12-19 07:27:36,906 - sources[DEBUG]: Update datasource metadata and network config due to events: boot-new-instance 2024-12-19 07:27:36,906 - util.py[DEBUG]: Reading from /sys/hypervisor/uuid (quiet=False) 2024-12-19 07:27:36,906 - util.py[DEBUG]: Read 37 bytes from /sys/hypervisor/uuid 2024-12-19 07:27:36,906 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_serial 2024-12-19 07:27:36,906 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/chassis_asset_tag 2024-12-19 07:27:36,906 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/sys_vendor 2024-12-19 07:27:36,906 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_name 2024-12-19 07:27:36,907 - sources[DEBUG]: Detected DataSourceEc2Local 2024-12-19 07:27:36,907 - DataSourceEc2.py[DEBUG]: strict_mode: warn, cloud_name=aws cloud_platform=ec2 2024-12-19 07:27:36,907 - util.py[DEBUG]: Reading from /sys/class/net/enX0/name_assign_type (quiet=False) 2024-12-19 07:27:36,907 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/name_assign_type 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/enX0/carrier (quiet=False) 2024-12-19 07:27:36,908 - net[DEBUG]: Interface has no carrier: enX0 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/enX0/dormant (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Reading from /sys/class/net/enX0/operstate (quiet=False) 2024-12-19 07:27:36,908 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/enX0/operstate 2024-12-19 07:27:36,909 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:36,924 - distros[DEBUG]: Using configured dhcp client priority list: ['dhcpcd', 'dhclient', 'udhcpc'] 2024-12-19 07:27:36,924 - distros[DEBUG]: DHCP client selected: dhcpcd 2024-12-19 07:27:36,924 - dhcp.py[DEBUG]: Performing a dhcp discovery on enX0 2024-12-19 07:27:36,924 - subp.py[DEBUG]: Running command ['ip', 'link', 'set', 'dev', 'enX0', 'up'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:36,926 - util.py[DEBUG]: Reading from /sys/class/net/enX0/type (quiet=False) 2024-12-19 07:27:36,926 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/type 2024-12-19 07:27:36,926 - subp.py[DEBUG]: Running command ['/usr/sbin/dhcpcd', '--ipv4only', '--waitip', '--persistent', '--noarp', '--script=/bin/true', 'enX0'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,181 - subp.py[DEBUG]: ['/usr/sbin/dhcpcd', '--ipv4only', '--waitip', '--persistent', '--noarp', '--script=/bin/true', 'enX0'] took 1.2s to run 2024-12-19 07:27:38,182 - subp.py[DEBUG]: Running command ['/usr/sbin/dhcpcd', '--dumplease', '--ipv4only', 'enX0'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,186 - dhcp.py[DEBUG]: Parsing dhcpcd lease for interface enX0: 'reason=REBOOT\ninterface=enX0\nprotocol=dhcp\nip_address=172.31.16.204\nsubnet_cidr=20\nnetwork_number=172.31.16.0\nsubnet_mask=255.255.240.0\nrouters=172.31.16.1\ndomain_name_servers=172.31.0.2\nhost_name=ip-172-31-16-204\ndomain_name=ap-southeast-1.compute.internal\ninterface_mtu=9001\nbroadcast_address=172.31.31.255\ndhcp_lease_time=3600\ndhcp_message_type=5\ndhcp_server_identifier=172.31.16.1\n' 2024-12-19 07:27:38,186 - util.py[DEBUG]: Reading from /var/lib/dhcpcd/enX0.lease (quiet=False) 2024-12-19 07:27:38,186 - util.py[DEBUG]: Read 548 bytes from /var/lib/dhcpcd/enX0.lease 2024-12-19 07:27:38,186 - subp.py[DEBUG]: Running command ['/usr/sbin/dhcpcd', '--ipv4only', '--waitip', '--persistent', '--noarp', '--script=/bin/true', 'enX0', '-P'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,188 - util.py[DEBUG]: Reading from /run/dhcpcd/enX0-4.pid (quiet=False) 2024-12-19 07:27:38,188 - util.py[DEBUG]: Read 4 bytes from /run/dhcpcd/enX0-4.pid 2024-12-19 07:27:38,188 - util.py[DEBUG]: Reading from /proc/431/stat (quiet=True) 2024-12-19 07:27:38,188 - util.py[DEBUG]: Read 299 bytes from /proc/431/stat 2024-12-19 07:27:38,189 - dhcp.py[DEBUG]: killing dhcpcd with pid=431 gid=430 2024-12-19 07:27:38,189 - ephemeral.py[DEBUG]: Received dhcp lease on enX0 for 172.31.16.204/255.255.240.0 2024-12-19 07:27:38,189 - ephemeral.py[DEBUG]: Attempting setup of ephemeral network on enX0 with 172.31.16.204/20 brd 172.31.31.255 2024-12-19 07:27:38,190 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,192 - ephemeral.py[DEBUG]: Skip adding ip address: enX0 already has address 172.31.16.204 2024-12-19 07:27:38,192 - ephemeral.py[DEBUG]: Skip bringing up network link: interface enX0 is already up 2024-12-19 07:27:38,192 - subp.py[DEBUG]: Running command ['ip', 'route', 'show', '0.0.0.0/0'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,193 - ephemeral.py[DEBUG]: Skip ephemeral route setup. enX0 already has default route: default via 172.31.16.1 dev enX0 proto dhcp src 172.31.16.204 metric 1002 mtu 9001 2024-12-19 07:27:38,193 - util.py[DEBUG]: Reading from /sys/class/net/enX0/operstate (quiet=False) 2024-12-19 07:27:38,193 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/enX0/operstate 2024-12-19 07:27:38,195 - util.py[DEBUG]: Resolving URL: http://169.254.169.254 took 0.002 seconds 2024-12-19 07:27:38,195 - util.py[DEBUG]: Resolving URL: http://[fd00:ec2::254] took 0.000 seconds 2024-12-19 07:27:38,195 - util.py[DEBUG]: Resolving URL: http://instance-data.:8773 took 0.000 seconds 2024-12-19 07:27:38,195 - DataSourceEc2.py[DEBUG]: Removed the following from metadata urls: ['http://instance-data.:8773'] 2024-12-19 07:27:38,195 - DataSourceEc2.py[DEBUG]: Fetching Ec2 IMDSv2 API Token 2024-12-19 07:27:38,196 - url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/latest/api/token' with {'url': 'http://169.254.169.254/latest/api/token', 'stream': False, 'allow_redirects': True, 'method': 'PUT', 'timeout': 50.0, 'headers': {'X-aws-ec2-metadata-token-ttl-seconds': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,201 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/api/token (200, 56b) after 1 attempts 2024-12-19 07:27:38,201 - DataSourceEc2.py[DEBUG]: Using metadata source: 'http://169.254.169.254' 2024-12-19 07:27:38,201 - url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2021-03-23/meta-data/instance-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,204 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/instance-id (200, 19b) after 1 attempts 2024-12-19 07:27:38,204 - DataSourceEc2.py[DEBUG]: Found preferred metadata version 2021-03-23 2024-12-19 07:27:38,204 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/user-data' with {'url': 'http://169.254.169.254/2021-03-23/user-data', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,206 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/user-data (200, 64b) after 1 attempts 2024-12-19 07:27:38,206 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,207 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/ (200, 313b) after 1 attempts 2024-12-19 07:27:38,207 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,210 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ (200, 30b) after 1 attempts 2024-12-19 07:27:38,210 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ami' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ami', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,212 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ami (200, 4b) after 1 attempts 2024-12-19 07:27:38,212 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral0' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral0', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,214 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral0 (200, 3b) after 1 attempts 2024-12-19 07:27:38,214 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral1' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral1', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,216 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/ephemeral1 (200, 3b) after 1 attempts 2024-12-19 07:27:38,216 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/root' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/root', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,218 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/block-device-mapping/root (200, 9b) after 1 attempts 2024-12-19 07:27:38,218 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/events/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/events/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,219 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/events/ (200, 12b) after 1 attempts 2024-12-19 07:27:38,220 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,222 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/events/maintenance/ (200, 17b) after 1 attempts 2024-12-19 07:27:38,222 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/history' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/history', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,223 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/events/maintenance/history (200, 2b) after 1 attempts 2024-12-19 07:27:38,223 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/scheduled' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/events/maintenance/scheduled', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,225 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/events/maintenance/scheduled (200, 2b) after 1 attempts 2024-12-19 07:27:38,225 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,226 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ (200, 4b) after 1 attempts 2024-12-19 07:27:38,227 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,228 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/ (200, 26b) after 1 attempts 2024-12-19 07:27:38,229 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/info' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/info', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,230 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/identity-credentials/ec2/info (200, 98b) after 1 attempts 2024-12-19 07:27:38,230 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/metrics/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/metrics/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,231 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/metrics/ (200, 7b) after 1 attempts 2024-12-19 07:27:38,231 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/metrics/vhostmd' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/metrics/vhostmd', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,233 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/metrics/vhostmd (200, 38b) after 1 attempts 2024-12-19 07:27:38,233 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,235 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/ (200, 11b) after 1 attempts 2024-12-19 07:27:38,235 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,237 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/ (200, 5b) after 1 attempts 2024-12-19 07:27:38,238 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,239 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/ (200, 18b) after 1 attempts 2024-12-19 07:27:38,240 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,241 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ (200, 230b) after 1 attempts 2024-12-19 07:27:38,241 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,243 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/ (200, 12b) after 1 attempts 2024-12-19 07:27:38,243 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/54.251.93.51' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/54.251.93.51', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,245 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/ipv4-associations/54.251.93.51 (200, 13b) after 1 attempts 2024-12-19 07:27:38,245 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/device-number' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/device-number', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,247 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/device-number (200, 1b) after 1 attempts 2024-12-19 07:27:38,247 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/interface-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/interface-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,249 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/interface-id (200, 21b) after 1 attempts 2024-12-19 07:27:38,250 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-hostname' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,251 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-hostname (200, 48b) after 1 attempts 2024-12-19 07:27:38,251 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-ipv4s' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-ipv4s', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,252 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/local-ipv4s (200, 13b) after 1 attempts 2024-12-19 07:27:38,253 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/mac' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/mac', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,254 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/mac (200, 17b) after 1 attempts 2024-12-19 07:27:38,254 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/owner-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/owner-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,256 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/owner-id (200, 12b) after 1 attempts 2024-12-19 07:27:38,257 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-hostname' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,260 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-hostname (200, 53b) after 1 attempts 2024-12-19 07:27:38,260 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-ipv4s' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-ipv4s', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,262 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/public-ipv4s (200, 12b) after 1 attempts 2024-12-19 07:27:38,262 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-group-ids' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-group-ids', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,266 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-group-ids (200, 20b) after 1 attempts 2024-12-19 07:27:38,266 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-groups' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-groups', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,269 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/security-groups (200, 7b) after 1 attempts 2024-12-19 07:27:38,269 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,271 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-id (200, 24b) after 1 attempts 2024-12-19 07:27:38,271 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-ipv4-cidr-block' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-ipv4-cidr-block', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,274 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/subnet-ipv4-cidr-block (200, 14b) after 1 attempts 2024-12-19 07:27:38,274 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,277 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-id (200, 21b) after 1 attempts 2024-12-19 07:27:38,277 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-block' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-block', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,280 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-block (200, 13b) after 1 attempts 2024-12-19 07:27:38,280 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-blocks' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-blocks', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,283 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/network/interfaces/macs/02:c9:ba:5b:38:29/vpc-ipv4-cidr-blocks (200, 13b) after 1 attempts 2024-12-19 07:27:38,283 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/placement/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/placement/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,286 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/placement/ (200, 45b) after 1 attempts 2024-12-19 07:27:38,287 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,290 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone (200, 15b) after 1 attempts 2024-12-19 07:27:38,290 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,293 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/placement/availability-zone-id (200, 9b) after 1 attempts 2024-12-19 07:27:38,293 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/placement/region' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/placement/region', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,296 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/placement/region (200, 14b) after 1 attempts 2024-12-19 07:27:38,297 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/public-keys/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/public-keys/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,298 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/public-keys/ (200, 29b) after 1 attempts 2024-12-19 07:27:38,298 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/public-keys/0/openssh-key' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/public-keys/0/openssh-key', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,300 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/public-keys/0/openssh-key (200, 409b) after 1 attempts 2024-12-19 07:27:38,300 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/services/' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/services/', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,302 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/services/ (200, 16b) after 1 attempts 2024-12-19 07:27:38,302 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/services/domain' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/services/domain', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,304 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/services/domain (200, 13b) after 1 attempts 2024-12-19 07:27:38,304 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/services/partition' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/services/partition', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,306 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/services/partition (200, 3b) after 1 attempts 2024-12-19 07:27:38,306 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/ami-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/ami-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,307 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/ami-id (200, 21b) after 1 attempts 2024-12-19 07:27:38,307 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/ami-launch-index' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/ami-launch-index', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,309 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/ami-launch-index (200, 1b) after 1 attempts 2024-12-19 07:27:38,309 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/ami-manifest-path' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/ami-manifest-path', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,310 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/ami-manifest-path (200, 9b) after 1 attempts 2024-12-19 07:27:38,311 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/hostname' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,312 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/hostname (200, 48b) after 1 attempts 2024-12-19 07:27:38,313 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/instance-action' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/instance-action', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,314 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/instance-action (200, 4b) after 1 attempts 2024-12-19 07:27:38,314 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/instance-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/instance-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,316 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/instance-id (200, 19b) after 1 attempts 2024-12-19 07:27:38,316 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/instance-life-cycle' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/instance-life-cycle', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,317 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/instance-life-cycle (200, 9b) after 1 attempts 2024-12-19 07:27:38,318 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/instance-type' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/instance-type', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,319 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/instance-type (200, 8b) after 1 attempts 2024-12-19 07:27:38,319 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/local-hostname' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/local-hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,320 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/local-hostname (200, 48b) after 1 attempts 2024-12-19 07:27:38,321 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/local-ipv4' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/local-ipv4', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,322 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/local-ipv4 (200, 13b) after 1 attempts 2024-12-19 07:27:38,322 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/mac' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/mac', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,323 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/mac (200, 17b) after 1 attempts 2024-12-19 07:27:38,324 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/profile' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/profile', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,326 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/profile (200, 11b) after 1 attempts 2024-12-19 07:27:38,326 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/public-hostname' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/public-hostname', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,328 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/public-hostname (200, 53b) after 1 attempts 2024-12-19 07:27:38,328 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/public-ipv4' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/public-ipv4', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,331 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/public-ipv4 (200, 12b) after 1 attempts 2024-12-19 07:27:38,331 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/reservation-id' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/reservation-id', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,333 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/reservation-id (200, 19b) after 1 attempts 2024-12-19 07:27:38,333 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/meta-data/security-groups' with {'url': 'http://169.254.169.254/2021-03-23/meta-data/security-groups', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,334 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/meta-data/security-groups (200, 7b) after 1 attempts 2024-12-19 07:27:38,334 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/dynamic/instance-identity' with {'url': 'http://169.254.169.254/2021-03-23/dynamic/instance-identity', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,336 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/dynamic/instance-identity (200, 32b) after 1 attempts 2024-12-19 07:27:38,336 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/document' with {'url': 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/document', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,338 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/dynamic/instance-identity/document (200, 487b) after 1 attempts 2024-12-19 07:27:38,338 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/pkcs7' with {'url': 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/pkcs7', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,341 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/dynamic/instance-identity/pkcs7 (200, 1183b) after 1 attempts 2024-12-19 07:27:38,341 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/rsa2048' with {'url': 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/rsa2048', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,343 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/dynamic/instance-identity/rsa2048 (200, 1507b) after 1 attempts 2024-12-19 07:27:38,344 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/signature' with {'url': 'http://169.254.169.254/2021-03-23/dynamic/instance-identity/signature', 'stream': False, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0, 'headers': {'X-aws-ec2-metadata-token': 'REDACTED', 'User-Agent': 'Cloud-Init/24.3.1-0ubuntu0~24.04.2'}} configuration 2024-12-19 07:27:38,345 - url_helper.py[DEBUG]: Read from http://169.254.169.254/2021-03-23/dynamic/instance-identity/signature (200, 174b) after 1 attempts 2024-12-19 07:27:38,345 - util.py[DEBUG]: Crawl of metadata service took 0.152 seconds 2024-12-19 07:27:38,346 - subp.py[DEBUG]: Running command ['ip', '-family', 'inet', 'link', 'set', 'dev', 'enX0', 'down'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,347 - subp.py[DEBUG]: Running command ['ip', '-family', 'inet', 'addr', 'del', '172.31.16.204/20', 'dev', 'enX0'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,350 - util.py[DEBUG]: Writing to /run/cloud-init/cloud-id-aws - wb: [644] 4 bytes 2024-12-19 07:27:38,351 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-aws' 2024-12-19 07:27:38,352 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpjnat_3oj) - w: [600] 15094 bytes/chars 2024-12-19 07:27:38,352 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpvioz5ao5) - w: [644] 8539 bytes/chars 2024-12-19 07:27:38,353 - handlers.py[DEBUG]: finish: init-local/search-Ec2Local: SUCCESS: found local data from DataSourceEc2Local 2024-12-19 07:27:38,353 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2024-12-19 07:27:38,353 - stages.py[INFO]: Loaded datasource DataSourceEc2Local - DataSourceEc2Local 2024-12-19 07:27:38,353 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-12-19 07:27:38,353 - util.py[DEBUG]: Read 3718 bytes from /etc/cloud/cloud.cfg 2024-12-19 07:27:38,353 - util.py[DEBUG]: Attempting to load yaml from string of length 3718 with allowed root types (,) 2024-12-19 07:27:38,360 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-12-19 07:27:38,360 - util.py[DEBUG]: Read 333 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-12-19 07:27:38,360 - util.py[DEBUG]: Attempting to load yaml from string of length 333 with allowed root types (,) 2024-12-19 07:27:38,361 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg (quiet=False) 2024-12-19 07:27:38,361 - util.py[DEBUG]: Read 108 bytes from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg 2024-12-19 07:27:38,361 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (,) 2024-12-19 07:27:38,361 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-12-19 07:27:38,361 - util.py[DEBUG]: Read 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-12-19 07:27:38,361 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (,) 2024-12-19 07:27:38,364 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-12-19 07:27:38,364 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-12-19 07:27:38,364 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-12-19 07:27:38,364 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:38,364 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:38,364 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2024-12-19 07:27:38,364 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/i-08df9382ffdaad8f0' 2024-12-19 07:27:38,366 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/i-08df9382ffdaad8f0/datasource (quiet=False) 2024-12-19 07:27:38,366 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/datasource - wb: [644] 39 bytes 2024-12-19 07:27:38,366 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 39 bytes 2024-12-19 07:27:38,367 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2024-12-19 07:27:38,367 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID 2024-12-19 07:27:38,367 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 20 bytes 2024-12-19 07:27:38,367 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 20 bytes 2024-12-19 07:27:38,367 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes 2024-12-19 07:27:38,368 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 12532 bytes 2024-12-19 07:27:38,368 - main.py[DEBUG]: [local] init will now be targeting instance id: i-08df9382ffdaad8f0. new=True 2024-12-19 07:27:38,368 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-12-19 07:27:38,368 - util.py[DEBUG]: Read 3718 bytes from /etc/cloud/cloud.cfg 2024-12-19 07:27:38,368 - util.py[DEBUG]: Attempting to load yaml from string of length 3718 with allowed root types (,) 2024-12-19 07:27:38,374 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-12-19 07:27:38,374 - util.py[DEBUG]: Read 333 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-12-19 07:27:38,375 - util.py[DEBUG]: Attempting to load yaml from string of length 333 with allowed root types (,) 2024-12-19 07:27:38,376 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg (quiet=False) 2024-12-19 07:27:38,376 - util.py[DEBUG]: Read 108 bytes from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg 2024-12-19 07:27:38,376 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (,) 2024-12-19 07:27:38,376 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-12-19 07:27:38,376 - util.py[DEBUG]: Read 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-12-19 07:27:38,376 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (,) 2024-12-19 07:27:38,377 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-12-19 07:27:38,378 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-12-19 07:27:38,378 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-12-19 07:27:38,378 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:38,378 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:38,379 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:38,380 - cc_set_hostname.py[DEBUG]: Setting the hostname to ip-172-31-16-204.ap-southeast-1.compute.internal (ip-172-31-16-204) 2024-12-19 07:27:38,380 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2024-12-19 07:27:38,380 - util.py[DEBUG]: Read 17 bytes from /etc/hostname 2024-12-19 07:27:38,380 - util.py[DEBUG]: Writing to /etc/hostname - wb: [644] 17 bytes 2024-12-19 07:27:38,380 - distros[DEBUG]: Non-persistently setting the system hostname to ip-172-31-16-204 2024-12-19 07:27:38,380 - subp.py[DEBUG]: Running command ['hostname', 'ip-172-31-16-204'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,382 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmpc29gz_iy) - w: [644] 97 bytes/chars 2024-12-19 07:27:38,383 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,383 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,383 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,383 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,383 - util.py[DEBUG]: Reading from /sys/class/net/enX0/name_assign_type (quiet=False) 2024-12-19 07:27:38,383 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/name_assign_type 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/carrier (quiet=False) 2024-12-19 07:27:38,384 - net[DEBUG]: Interface has no carrier: enX0 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/dormant (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/operstate (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/enX0/operstate 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,384 - net[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/addr_assign_type (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/addr_assign_type 2024-12-19 07:27:38,384 - util.py[DEBUG]: Reading from /sys/class/net/enX0/uevent (quiet=False) 2024-12-19 07:27:38,384 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/enX0/uevent 2024-12-19 07:27:38,385 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,385 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,385 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,385 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2024-12-19 07:27:38,385 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2024-12-19 07:27:38,385 - util.py[DEBUG]: Reading from /sys/class/net/enX0/type (quiet=False) 2024-12-19 07:27:38,385 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/type 2024-12-19 07:27:38,386 - distros[DEBUG]: Selected renderer 'netplan' from priority list: ['netplan', 'eni', 'sysconfig'] 2024-12-19 07:27:38,386 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,386 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,386 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,386 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,386 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/instance/network-config.json (via temporary file /var/lib/cloud/instance/tmpooryjuhd) - w: [600] 171 bytes/chars 2024-12-19 07:27:38,386 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/network-config.json' => '/var/lib/cloud/instance/network-config.json' 2024-12-19 07:27:38,386 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-network-config-v2.json (quiet=False) 2024-12-19 07:27:38,389 - util.py[DEBUG]: Read 17775 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-network-config-v2.json 2024-12-19 07:27:38,404 - util.py[DEBUG]: Writing to /run/cloud-init/tmp/tmpvrvru8il/etc/netplan/network-config.yaml - wb: [600] 202 bytes 2024-12-19 07:27:38,405 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,405 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-12-19 07:27:38,405 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-12-19 07:27:38,405 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/addr_assign_type (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/addr_assign_type 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/uevent (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/enX0/uevent 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/type (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/type 2024-12-19 07:27:38,406 - networking.py[DEBUG]: net: all expected physical devices present 2024-12-19 07:27:38,406 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'enX0': {'dhcp4': True, 'dhcp6': False, 'match': {'macaddress': '02:c9:ba:5b:38:29'}, 'set-name': 'enX0'}}} 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:38,406 - util.py[DEBUG]: Reading from /sys/class/net/enX0/addr_assign_type (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/addr_assign_type 2024-12-19 07:27:38,407 - util.py[DEBUG]: Reading from /sys/class/net/enX0/uevent (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/enX0/uevent 2024-12-19 07:27:38,407 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:38,407 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate 2024-12-19 07:27:38,407 - util.py[DEBUG]: Reading from /sys/class/net/enX0/operstate (quiet=False) 2024-12-19 07:27:38,407 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/enX0/operstate 2024-12-19 07:27:38,407 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,409 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,411 - net[DEBUG]: Detected interfaces {'lo': {'downable': False, 'device_id': None, 'driver': None, 'mac': '00:00:00:00:00:00', 'name': 'lo', 'up': True}, 'enX0': {'downable': True, 'device_id': None, 'driver': 'vif', 'mac': '02:c9:ba:5b:38:29', 'name': 'enX0', 'up': False}} 2024-12-19 07:27:38,411 - net[DEBUG]: no work necessary for renaming of [['02:c9:ba:5b:38:29', 'enX0', 'vif', None]] 2024-12-19 07:27:38,411 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 2, 'ethernets': {'enX0': {'dhcp4': True, 'dhcp6': False, 'match': {'macaddress': '02:c9:ba:5b:38:29'}, 'set-name': 'enX0'}}} 2024-12-19 07:27:38,412 - util.py[DEBUG]: Writing to /run/cloud-init/sem/apply_network_config.once - wb: [644] 24 bytes 2024-12-19 07:27:38,412 - distros[DEBUG]: Selected renderer 'netplan' from priority list: ['netplan', 'eni', 'sysconfig'] 2024-12-19 07:27:38,412 - network_state.py[DEBUG]: Passthrough netplan v2 config 2024-12-19 07:27:38,412 - netplan.py[DEBUG]: V2 to V2 passthrough 2024-12-19 07:27:38,413 - util.py[DEBUG]: Reading from /etc/netplan/50-cloud-init.yaml (quiet=False) 2024-12-19 07:27:38,413 - util.py[DEBUG]: Read 158 bytes from /etc/netplan/50-cloud-init.yaml 2024-12-19 07:27:38,413 - util.py[DEBUG]: Attempting to load yaml from string of length 158 with allowed root types (,) 2024-12-19 07:27:38,413 - util.py[DEBUG]: Attempting to load yaml from string of length 506 with allowed root types (,) 2024-12-19 07:27:38,415 - netplan.py[DEBUG]: Rendered netplan config using netplan python API 2024-12-19 07:27:38,416 - netplan.py[DEBUG]: skipping call to `netplan generate`. reason: identical netplan config 2024-12-19 07:27:38,416 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/lo'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,420 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/enX0'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:38,424 - distros[DEBUG]: Not bringing up newly configured network interfaces 2024-12-19 07:27:38,424 - main.py[DEBUG]: [local] Exiting. datasource DataSourceEc2Local not in local mode. 2024-12-19 07:27:38,424 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:38,424 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-12-19 07:27:38,424 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp1x3yy2ur) - w: [644] 499 bytes/chars 2024-12-19 07:27:38,425 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:38,425 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-12-19 07:27:38,425 - util.py[DEBUG]: cloud-init mode 'init' took 1.587 seconds (1.59) 2024-12-19 07:27:38,425 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources 2024-12-19 07:27:40,631 - util.py[DEBUG]: Cloud-init v. 24.3.1-0ubuntu0~24.04.2 running 'init' at Thu, 19 Dec 2024 07:27:40 +0000. Up 8.70 seconds. 2024-12-19 07:27:40,634 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:40,635 - main.py[INFO]: PID [1] started cloud-init 'init'. 2024-12-19 07:27:40,635 - main.py[DEBUG]: No kernel command line url found. 2024-12-19 07:27:40,635 - main.py[DEBUG]: Closing stdin 2024-12-19 07:27:40,636 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [640] 0 bytes 2024-12-19 07:27:40,636 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 102:4 2024-12-19 07:27:40,636 - subp.py[DEBUG]: Running command ['ip', '--json', 'addr'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,638 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,640 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) 2024-12-19 07:27:40,645 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] 2024-12-19 07:27:40,647 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2024-12-19 07:27:40,648 - util.py[DEBUG]: Read 12532 bytes from /var/lib/cloud/instance/obj.pkl 2024-12-19 07:27:40,682 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) 2024-12-19 07:27:40,682 - util.py[DEBUG]: Read 20 bytes from /run/cloud-init/.instance-id 2024-12-19 07:27:40,682 - stages.py[DEBUG]: restored from cache with run check: DataSourceEc2Local 2024-12-19 07:27:40,682 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceEc2Local 2024-12-19 07:27:40,682 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-12-19 07:27:40,682 - util.py[DEBUG]: Read 3718 bytes from /etc/cloud/cloud.cfg 2024-12-19 07:27:40,682 - util.py[DEBUG]: Attempting to load yaml from string of length 3718 with allowed root types (,) 2024-12-19 07:27:40,687 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-12-19 07:27:40,688 - util.py[DEBUG]: Read 333 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-12-19 07:27:40,688 - util.py[DEBUG]: Attempting to load yaml from string of length 333 with allowed root types (,) 2024-12-19 07:27:40,688 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg (quiet=False) 2024-12-19 07:27:40,688 - util.py[DEBUG]: Read 108 bytes from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg 2024-12-19 07:27:40,688 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (,) 2024-12-19 07:27:40,689 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-12-19 07:27:40,689 - util.py[DEBUG]: Read 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-12-19 07:27:40,689 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (,) 2024-12-19 07:27:40,690 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-12-19 07:27:40,690 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-12-19 07:27:40,690 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-12-19 07:27:40,690 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:40,690 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:40,691 - stages.py[INFO]: Instance link already exists, not recreating it. 2024-12-19 07:27:40,691 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/i-08df9382ffdaad8f0/datasource (quiet=False) 2024-12-19 07:27:40,691 - util.py[DEBUG]: Read 39 bytes from /var/lib/cloud/instances/i-08df9382ffdaad8f0/datasource 2024-12-19 07:27:40,691 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/datasource - wb: [644] 39 bytes 2024-12-19 07:27:40,692 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 39 bytes 2024-12-19 07:27:40,692 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2024-12-19 07:27:40,692 - util.py[DEBUG]: Read 20 bytes from /var/lib/cloud/data/instance-id 2024-12-19 07:27:40,692 - stages.py[DEBUG]: previous iid found to be i-08df9382ffdaad8f0 2024-12-19 07:27:40,692 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 20 bytes 2024-12-19 07:27:40,692 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 20 bytes 2024-12-19 07:27:40,692 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 20 bytes 2024-12-19 07:27:40,693 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 12657 bytes 2024-12-19 07:27:40,693 - stages.py[INFO]: Not re-loading configuration, instance id and datasource have not changed. 2024-12-19 07:27:40,693 - main.py[DEBUG]: [net] init will now be targeting instance id: i-08df9382ffdaad8f0. new=False 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:40,694 - net[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:40,694 - util.py[DEBUG]: Reading from /sys/class/net/enX0/addr_assign_type (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/addr_assign_type 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /sys/class/net/enX0/uevent (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/enX0/uevent 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /sys/class/net/enX0/type (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/type 2024-12-19 07:27:40,695 - distros[DEBUG]: Selected renderer 'netplan' from priority list: ['netplan', 'eni', 'sysconfig'] 2024-12-19 07:27:40,695 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False) 2024-12-19 07:27:40,695 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled 2024-12-19 07:27:40,695 - stages.py[DEBUG]: Allowed events: {: {, }} 2024-12-19 07:27:40,695 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy 2024-12-19 07:27:40,696 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed 2024-12-19 07:27:40,696 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'enX0': {'dhcp4': True, 'dhcp6': False, 'match': {'macaddress': '02:c9:ba:5b:38:29'}, 'set-name': 'enX0'}}} 2024-12-19 07:27:40,696 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/enX0/addr_assign_type (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/enX0/addr_assign_type 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/enX0/uevent (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/enX0/uevent 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/enX0/address (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/enX0/address 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/enX0/device/device (quiet=False) 2024-12-19 07:27:40,696 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) 2024-12-19 07:27:40,697 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate 2024-12-19 07:27:40,697 - util.py[DEBUG]: Reading from /sys/class/net/enX0/operstate (quiet=False) 2024-12-19 07:27:40,697 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/enX0/operstate 2024-12-19 07:27:40,697 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,698 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,699 - net[DEBUG]: Detected interfaces {'lo': {'downable': False, 'device_id': None, 'driver': None, 'mac': '00:00:00:00:00:00', 'name': 'lo', 'up': True}, 'enX0': {'downable': False, 'device_id': None, 'driver': 'vif', 'mac': '02:c9:ba:5b:38:29', 'name': 'enX0', 'up': True}} 2024-12-19 07:27:40,700 - net[DEBUG]: no work necessary for renaming of [['02:c9:ba:5b:38:29', 'enX0', 'vif', None]] 2024-12-19 07:27:40,700 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource 2024-12-19 07:27:40,700 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource 2024-12-19 07:27:40,700 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/user-data.txt - wb: [600] 64 bytes 2024-12-19 07:27:40,702 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/user-data.txt.i - wb: [600] 370 bytes 2024-12-19 07:27:40,702 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/vendor-data.txt - wb: [600] 0 bytes 2024-12-19 07:27:40,703 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/vendor-data.txt.i - wb: [600] 308 bytes 2024-12-19 07:27:40,703 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/vendor-data2.txt - wb: [600] 0 bytes 2024-12-19 07:27:40,703 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/vendor-data2.txt.i - wb: [600] 308 bytes 2024-12-19 07:27:40,704 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) 2024-12-19 07:27:40,704 - util.py[DEBUG]: Read 97 bytes from /var/lib/cloud/data/set-hostname 2024-12-19 07:27:40,704 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname 2024-12-19 07:27:40,704 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/consume_data - wb: [644] 24 bytes 2024-12-19 07:27:40,704 - helpers.py[DEBUG]: Running consume_data using lock () 2024-12-19 07:27:40,704 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data 2024-12-19 07:27:40,705 - launch_index.py[DEBUG]: Discarding 0 multipart messages which do not match launch index 0 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-boot'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-instance'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript-per-once'} from ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] 2024-12-19 07:27:40,705 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__begin__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__begin__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__begin__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance 2024-12-19 07:27:40,705 - handlers[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/x-shellscript', 'Content-Disposition': 'attachment; filename="part-001"'} 2024-12-19 07:27:40,705 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (text/x-shellscript, part-001, 2) with frequency once-per-instance 2024-12-19 07:27:40,705 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/scripts/part-001 - wb: [700] 64 bytes 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance 2024-12-19 07:27:40,706 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/cloud-config.txt - wb: [600] 0 bytes 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-boot']] (__end__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-instance']] (__end__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler ShellScriptByFreqPartHandler: [['text/x-shellscript-per-once']] (__end__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance 2024-12-19 07:27:40,706 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data 2024-12-19 07:27:40,706 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data 2024-12-19 07:27:40,706 - stages.py[DEBUG]: no vendordata from datasource 2024-12-19 07:27:40,706 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data 2024-12-19 07:27:40,706 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 2024-12-19 07:27:40,706 - stages.py[DEBUG]: no vendordata2 from datasource 2024-12-19 07:27:40,706 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 2024-12-19 07:27:40,706 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-12-19 07:27:40,706 - util.py[DEBUG]: Read 3718 bytes from /etc/cloud/cloud.cfg 2024-12-19 07:27:40,707 - util.py[DEBUG]: Attempting to load yaml from string of length 3718 with allowed root types (,) 2024-12-19 07:27:40,712 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-12-19 07:27:40,712 - util.py[DEBUG]: Read 333 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-12-19 07:27:40,712 - util.py[DEBUG]: Attempting to load yaml from string of length 333 with allowed root types (,) 2024-12-19 07:27:40,712 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg (quiet=False) 2024-12-19 07:27:40,713 - util.py[DEBUG]: Read 108 bytes from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg 2024-12-19 07:27:40,713 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (,) 2024-12-19 07:27:40,713 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-12-19 07:27:40,713 - util.py[DEBUG]: Read 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-12-19 07:27:40,713 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (,) 2024-12-19 07:27:40,714 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-12-19 07:27:40,714 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-12-19 07:27:40,714 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-12-19 07:27:40,714 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:40,714 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:40,715 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-12-19 07:27:40,715 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-12-19 07:27:40,715 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:40,715 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:40,715 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/combined-cloud-config.json (via temporary file /run/cloud-init/tmp3ijos6l3) - w: [600] 5577 bytes/chars 2024-12-19 07:27:40,716 - util.py[DEBUG]: Reading from /run/cloud-init/instance-data-sensitive.json (quiet=False) 2024-12-19 07:27:40,716 - util.py[DEBUG]: Read 15094 bytes from /run/cloud-init/instance-data-sensitive.json 2024-12-19 07:27:40,716 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmp031zr2dq) - w: [600] 17293 bytes/chars 2024-12-19 07:27:40,716 - main.py[DEBUG]: Skipping user-data validation. No user-data found. 2024-12-19 07:27:40,716 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-12-19 07:27:40,716 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-12-19 07:27:40,716 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:40,717 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:40,718 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource 2024-12-19 07:27:40,718 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 15119 bytes 2024-12-19 07:27:40,719 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource 2024-12-19 07:27:40,719 - main.py[DEBUG]: no di_report found in config. 2024-12-19 07:27:40,734 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:40,735 - modules.py[INFO]: Skipping modules 'bootcmd,write_files,disk_setup,update_etc_hosts,ca_certs,rsyslog' because no applicable config is provided. 2024-12-19 07:27:40,735 - modules.py[DEBUG]: Running module seed_random () with frequency once-per-instance 2024-12-19 07:27:40,735 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance 2024-12-19 07:27:40,735 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_seed_random - wb: [644] 24 bytes 2024-12-19 07:27:40,735 - helpers.py[DEBUG]: Running config-seed_random using lock () 2024-12-19 07:27:40,736 - cc_seed_random.py[DEBUG]: no command provided 2024-12-19 07:27:40,736 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully 2024-12-19 07:27:40,736 - modules.py[DEBUG]: Running module growpart () with frequency always 2024-12-19 07:27:40,736 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always 2024-12-19 07:27:40,736 - helpers.py[DEBUG]: Running config-growpart using lock () 2024-12-19 07:27:40,736 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} 2024-12-19 07:27:40,736 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,739 - util.py[DEBUG]: Reading from /proc/462/mountinfo (quiet=False) 2024-12-19 07:27:40,739 - util.py[DEBUG]: Read 2554 bytes from /proc/462/mountinfo 2024-12-19 07:27:40,739 - cc_growpart.py[DEBUG]: growpart found fs=ext4 2024-12-19 07:27:40,739 - util.py[DEBUG]: Reading from /sys/class/block/xvda1/partition (quiet=False) 2024-12-19 07:27:40,739 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/xvda1/partition 2024-12-19 07:27:40,739 - util.py[DEBUG]: Reading from /sys/devices/vbd-768/block/xvda/dev (quiet=False) 2024-12-19 07:27:40,739 - util.py[DEBUG]: Read 6 bytes from /sys/devices/vbd-768/block/xvda/dev 2024-12-19 07:27:40,739 - util.py[DEBUG]: Reading from /proc/462/mountinfo (quiet=False) 2024-12-19 07:27:40,740 - util.py[DEBUG]: Read 2554 bytes from /proc/462/mountinfo 2024-12-19 07:27:40,740 - util.py[DEBUG]: Reading from /proc/462/mountinfo (quiet=False) 2024-12-19 07:27:40,740 - util.py[DEBUG]: Read 2554 bytes from /proc/462/mountinfo 2024-12-19 07:27:40,741 - subp.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/xvda', '1'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,795 - util.py[DEBUG]: resize_devices took 0.056 seconds 2024-12-19 07:27:40,795 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/xvda, 1) 2024-12-19 07:27:40,795 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully 2024-12-19 07:27:40,795 - modules.py[DEBUG]: Running module resizefs () with frequency always 2024-12-19 07:27:40,796 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always 2024-12-19 07:27:40,796 - helpers.py[DEBUG]: Running config-resizefs using lock () 2024-12-19 07:27:40,796 - util.py[DEBUG]: Reading from /proc/462/mountinfo (quiet=False) 2024-12-19 07:27:40,796 - util.py[DEBUG]: Read 2554 bytes from /proc/462/mountinfo 2024-12-19 07:27:40,796 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/root mnt_point=/ path=/ 2024-12-19 07:27:40,796 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/root 2024-12-19 07:27:40,796 - subp.py[DEBUG]: Running command ('resize2fs', '/dev/root') with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,800 - util.py[DEBUG]: Resizing took 0.004 seconds 2024-12-19 07:27:40,800 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) 2024-12-19 07:27:40,800 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully 2024-12-19 07:27:40,800 - modules.py[DEBUG]: Running module mounts () with frequency once-per-instance 2024-12-19 07:27:40,800 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance 2024-12-19 07:27:40,801 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_mounts - wb: [644] 22 bytes 2024-12-19 07:27:40,801 - helpers.py[DEBUG]: Running config-mounts using lock () 2024-12-19 07:27:40,801 - cc_mounts.py[DEBUG]: mounts configuration is [] 2024-12-19 07:27:40,801 - util.py[DEBUG]: Reading from /etc/fstab (quiet=False) 2024-12-19 07:27:40,801 - util.py[DEBUG]: Read 146 bytes from /etc/fstab 2024-12-19 07:27:40,801 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 2024-12-19 07:27:40,801 - cc_mounts.py[DEBUG]: changed ephemeral0 => None 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap 2024-12-19 07:27:40,802 - DataSourceEc2.py[DEBUG]: Unable to convert swap to a device 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: changed swap => None 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: no need to setup swap 2024-12-19 07:27:40,802 - cc_mounts.py[DEBUG]: No modifications to fstab needed 2024-12-19 07:27:40,802 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully 2024-12-19 07:27:40,802 - modules.py[DEBUG]: Running module set_hostname () with frequency once-per-instance 2024-12-19 07:27:40,802 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance 2024-12-19 07:27:40,802 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_set_hostname - wb: [644] 24 bytes 2024-12-19 07:27:40,803 - helpers.py[DEBUG]: Running config-set_hostname using lock () 2024-12-19 07:27:40,803 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) 2024-12-19 07:27:40,803 - util.py[DEBUG]: Read 97 bytes from /var/lib/cloud/data/set-hostname 2024-12-19 07:27:40,803 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set_hostname 2024-12-19 07:27:40,803 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully 2024-12-19 07:27:40,803 - modules.py[DEBUG]: Running module update_hostname () with frequency always 2024-12-19 07:27:40,803 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always 2024-12-19 07:27:40,803 - helpers.py[DEBUG]: Running config-update_hostname using lock () 2024-12-19 07:27:40,803 - cc_update_hostname.py[DEBUG]: Updating hostname to ip-172-31-16-204.ap-southeast-1.compute.internal (ip-172-31-16-204) 2024-12-19 07:27:40,803 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) 2024-12-19 07:27:40,803 - util.py[DEBUG]: Read 17 bytes from /etc/hostname 2024-12-19 07:27:40,803 - distros[DEBUG]: Attempting to update hostname to ip-172-31-16-204 in 1 files 2024-12-19 07:27:40,803 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) 2024-12-19 07:27:40,803 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 17 bytes 2024-12-19 07:27:40,804 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully 2024-12-19 07:27:40,804 - modules.py[DEBUG]: Running module users_groups () with frequency once-per-instance 2024-12-19 07:27:40,804 - handlers.py[DEBUG]: start: init-network/config-users_groups: running config-users_groups with frequency once-per-instance 2024-12-19 07:27:40,804 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_users_groups - wb: [644] 23 bytes 2024-12-19 07:27:40,804 - helpers.py[DEBUG]: Running config-users_groups using lock () 2024-12-19 07:27:40,805 - distros[INFO]: User ubuntu already exists, skipping. 2024-12-19 07:27:40,805 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) 2024-12-19 07:27:40,805 - util.py[DEBUG]: Read 400 bytes from /etc/os-release 2024-12-19 07:27:40,805 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) 2024-12-19 07:27:40,805 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini 2024-12-19 07:27:40,805 - util.py[DEBUG]: Reading from /etc/shadow (quiet=False) 2024-12-19 07:27:40,806 - util.py[DEBUG]: Read 899 bytes from /etc/shadow 2024-12-19 07:27:40,806 - distros[DEBUG]: User ubuntu found in /etc/shadow. Checking for empty password 2024-12-19 07:27:40,806 - subp.py[DEBUG]: Running command ['passwd', '-l', 'ubuntu'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:40,825 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) 2024-12-19 07:27:40,826 - util.py[DEBUG]: Read 1800 bytes from /etc/sudoers 2024-12-19 07:27:40,827 - util.py[DEBUG]: Reading from /etc/sudoers.d/90-cloud-init-users (quiet=False) 2024-12-19 07:27:40,827 - util.py[DEBUG]: Read 141 bytes from /etc/sudoers.d/90-cloud-init-users 2024-12-19 07:27:40,828 - handlers.py[DEBUG]: finish: init-network/config-users_groups: SUCCESS: config-users_groups ran successfully 2024-12-19 07:27:40,828 - modules.py[DEBUG]: Running module ssh () with frequency once-per-instance 2024-12-19 07:27:40,828 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance 2024-12-19 07:27:40,828 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_ssh - wb: [644] 24 bytes 2024-12-19 07:27:40,829 - helpers.py[DEBUG]: Running config-ssh using lock () 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_rsa_key 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ecdsa_key 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_rsa_key.pub 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ecdsa_key.pub 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ed25519_key.pub 2024-12-19 07:27:40,829 - util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ed25519_key 2024-12-19 07:27:40,829 - util.py[DEBUG]: Reading from /proc/sys/crypto/fips_enabled (quiet=False) 2024-12-19 07:27:40,830 - subp.py[DEBUG]: Running command ['ssh-keygen', '-t', 'rsa', '-N', '', '-f', '/etc/ssh/ssh_host_rsa_key'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:41,837 - subp.py[DEBUG]: ['ssh-keygen', '-t', 'rsa', '-N', '', '-f', '/etc/ssh/ssh_host_rsa_key'] took 1.0s to run 2024-12-19 07:27:41,837 - subp.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ecdsa', '-N', '', '-f', '/etc/ssh/ssh_host_ecdsa_key'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:41,844 - subp.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ed25519', '-N', '', '-f', '/etc/ssh/ssh_host_ed25519_key'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:41,849 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) 2024-12-19 07:27:41,849 - util.py[DEBUG]: Read 575 bytes from /etc/ssh/ssh_host_rsa_key.pub 2024-12-19 07:27:41,849 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) 2024-12-19 07:27:41,849 - util.py[DEBUG]: Read 183 bytes from /etc/ssh/ssh_host_ecdsa_key.pub 2024-12-19 07:27:41,849 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) 2024-12-19 07:27:41,850 - util.py[DEBUG]: Read 103 bytes from /etc/ssh/ssh_host_ed25519_key.pub 2024-12-19 07:27:41,850 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2024-12-19 07:27:41,850 - util.py[DEBUG]: Read 3255 bytes from /etc/ssh/sshd_config 2024-12-19 07:27:41,852 - util.py[DEBUG]: Reading from /home/ubuntu/.ssh/authorized_keys (quiet=False) 2024-12-19 07:27:41,853 - util.py[DEBUG]: Read 409 bytes from /home/ubuntu/.ssh/authorized_keys 2024-12-19 07:27:41,853 - util.py[DEBUG]: Writing to /home/ubuntu/.ssh/authorized_keys - wb: [600] 409 bytes 2024-12-19 07:27:41,853 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2024-12-19 07:27:41,853 - util.py[DEBUG]: Read 3255 bytes from /etc/ssh/sshd_config 2024-12-19 07:27:41,854 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False) 2024-12-19 07:27:41,855 - util.py[DEBUG]: Read 1731 bytes from /root/.ssh/authorized_keys 2024-12-19 07:27:41,855 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 1731 bytes 2024-12-19 07:27:41,855 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully 2024-12-19 07:27:41,856 - modules.py[DEBUG]: Running module set_passwords () with frequency once-per-instance 2024-12-19 07:27:41,856 - handlers.py[DEBUG]: start: init-network/config-set_passwords: running config-set_passwords with frequency once-per-instance 2024-12-19 07:27:41,856 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_set_passwords - wb: [644] 24 bytes 2024-12-19 07:27:41,856 - helpers.py[DEBUG]: Running config-set_passwords using lock () 2024-12-19 07:27:41,856 - cc_set_passwords.py[DEBUG]: Leaving SSH config 'PasswordAuthentication' unchanged. ssh_pwauth=None 2024-12-19 07:27:41,856 - handlers.py[DEBUG]: finish: init-network/config-set_passwords: SUCCESS: config-set_passwords ran successfully 2024-12-19 07:27:41,856 - main.py[DEBUG]: Ran 9 modules with 0 failures 2024-12-19 07:27:41,856 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:41,856 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-12-19 07:27:41,856 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp38wzwfht) - w: [644] 498 bytes/chars 2024-12-19 07:27:41,857 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:41,857 - util.py[DEBUG]: Read 10 bytes from /proc/uptime 2024-12-19 07:27:41,857 - util.py[DEBUG]: cloud-init mode 'init' took 1.262 seconds (1.26) 2024-12-19 07:27:41,857 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources 2024-12-19 07:27:44,432 - util.py[DEBUG]: Cloud-init v. 24.3.1-0ubuntu0~24.04.2 running 'modules:config' at Thu, 19 Dec 2024 07:27:44 +0000. Up 12.40 seconds. 2024-12-19 07:27:44,436 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:44,436 - main.py[INFO]: PID [1] started cloud-init 'modules:config'. 2024-12-19 07:27:44,453 - util.py[DEBUG]: Reading from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json (quiet=False) 2024-12-19 07:27:44,456 - util.py[DEBUG]: Read 149553 bytes from /usr/lib/python3/dist-packages/cloudinit/config/schemas/schema-cloud-config-v1.json 2024-12-19 07:27:44,458 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2024-12-19 07:27:44,458 - util.py[DEBUG]: Read 3718 bytes from /etc/cloud/cloud.cfg 2024-12-19 07:27:44,458 - util.py[DEBUG]: Attempting to load yaml from string of length 3718 with allowed root types (,) 2024-12-19 07:27:44,481 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2024-12-19 07:27:44,481 - util.py[DEBUG]: Read 333 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2024-12-19 07:27:44,482 - util.py[DEBUG]: Attempting to load yaml from string of length 333 with allowed root types (,) 2024-12-19 07:27:44,488 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg (quiet=False) 2024-12-19 07:27:44,488 - util.py[DEBUG]: Read 108 bytes from /etc/cloud/cloud.cfg.d/90-cpc-grub.cfg 2024-12-19 07:27:44,488 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (,) 2024-12-19 07:27:44,489 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2024-12-19 07:27:44,489 - util.py[DEBUG]: Read 2071 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2024-12-19 07:27:44,489 - util.py[DEBUG]: Attempting to load yaml from string of length 2071 with allowed root types (,) 2024-12-19 07:27:44,499 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2024-12-19 07:27:44,499 - util.py[DEBUG]: Read 31 bytes from /run/cloud-init/cloud.cfg 2024-12-19 07:27:44,499 - util.py[DEBUG]: Attempting to load yaml from string of length 31 with allowed root types (,) 2024-12-19 07:27:44,499 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:44,499 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:44,500 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2024-12-19 07:27:44,500 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2024-12-19 07:27:44,500 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2024-12-19 07:27:44,500 - util.py[DEBUG]: loaded blob returned None, returning default. 2024-12-19 07:27:44,530 - modules.py[INFO]: Skipping modules 'wireguard,snap,ubuntu_autoinstall,keyboard,apt_pipelining,ubuntu_pro,ntp,timezone,disable_ec2_metadata,runcmd' because no applicable config is provided. 2024-12-19 07:27:44,530 - modules.py[DEBUG]: Running module ssh_import_id () with frequency once-per-instance 2024-12-19 07:27:44,530 - handlers.py[DEBUG]: start: modules-config/config-ssh_import_id: running config-ssh_import_id with frequency once-per-instance 2024-12-19 07:27:44,530 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_ssh_import_id - wb: [644] 24 bytes 2024-12-19 07:27:44,531 - helpers.py[DEBUG]: Running config-ssh_import_id using lock () 2024-12-19 07:27:44,531 - cc_ssh_import_id.py[DEBUG]: Skipping module named ssh_import_id, no 'ssh_import_id' directives found. 2024-12-19 07:27:44,531 - handlers.py[DEBUG]: finish: modules-config/config-ssh_import_id: SUCCESS: config-ssh_import_id ran successfully 2024-12-19 07:27:44,531 - modules.py[DEBUG]: Running module locale () with frequency once-per-instance 2024-12-19 07:27:44,531 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance 2024-12-19 07:27:44,533 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_locale - wb: [644] 24 bytes 2024-12-19 07:27:44,533 - helpers.py[DEBUG]: Running config-locale using lock () 2024-12-19 07:27:44,533 - util.py[DEBUG]: Reading from /etc/default/locale (quiet=False) 2024-12-19 07:27:44,533 - util.py[DEBUG]: Read 13 bytes from /etc/default/locale 2024-12-19 07:27:44,533 - cc_locale.py[DEBUG]: Setting locale to C.UTF-8 2024-12-19 07:27:44,533 - debian.py[DEBUG]: System locale set to C.UTF-8 via /etc/default/locale 2024-12-19 07:27:44,533 - debian.py[DEBUG]: System has 'LANG=C.UTF-8' requested 'C.UTF-8', skipping regeneration. 2024-12-19 07:27:44,533 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully 2024-12-19 07:27:44,533 - modules.py[DEBUG]: Running module grub_dpkg () with frequency once-per-instance 2024-12-19 07:27:44,533 - handlers.py[DEBUG]: start: modules-config/config-grub_dpkg: running config-grub_dpkg with frequency once-per-instance 2024-12-19 07:27:44,533 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_grub_dpkg - wb: [644] 24 bytes 2024-12-19 07:27:44,534 - helpers.py[DEBUG]: Running config-grub_dpkg using lock () 2024-12-19 07:27:44,534 - cc_grub_dpkg.py[DEBUG]: grub_dpkg disabled by config grub_dpkg/enabled=False 2024-12-19 07:27:44,534 - handlers.py[DEBUG]: finish: modules-config/config-grub_dpkg: SUCCESS: config-grub_dpkg ran successfully 2024-12-19 07:27:44,534 - modules.py[DEBUG]: Running module apt_configure () with frequency once-per-instance 2024-12-19 07:27:44,534 - handlers.py[DEBUG]: start: modules-config/config-apt_configure: running config-apt_configure with frequency once-per-instance 2024-12-19 07:27:44,537 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_apt_configure - wb: [644] 23 bytes 2024-12-19 07:27:44,537 - helpers.py[DEBUG]: Running config-apt_configure using lock () 2024-12-19 07:27:44,537 - cc_apt_configure.py[DEBUG]: debconf_selections was not set in config 2024-12-19 07:27:44,537 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) 2024-12-19 07:27:44,537 - util.py[DEBUG]: Read 400 bytes from /etc/os-release 2024-12-19 07:27:44,537 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) 2024-12-19 07:27:44,537 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini 2024-12-19 07:27:44,542 - cc_apt_configure.py[DEBUG]: handling apt config: {} 2024-12-19 07:27:44,542 - subp.py[DEBUG]: Running command ['lsb_release', '--all'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:44,569 - subp.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:44,583 - cc_apt_configure.py[DEBUG]: got primary mirror: None 2024-12-19 07:27:44,584 - cc_apt_configure.py[DEBUG]: got security mirror: None 2024-12-19 07:27:44,584 - util.py[DEBUG]: search for mirror in candidates: '['http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/', 'http://ap-southeast-1a.clouds.archive.ubuntu.com/ubuntu/', 'http://ap-southeast-1.clouds.archive.ubuntu.com/ubuntu/']' 2024-12-19 07:27:44,596 - util.py[DEBUG]: Resolving URL: http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/ took 0.012 seconds 2024-12-19 07:27:44,596 - util.py[DEBUG]: found working mirror: 'http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/' 2024-12-19 07:27:44,596 - util.py[DEBUG]: search for mirror in candidates: '[]' 2024-12-19 07:27:44,596 - distros[DEBUG]: filtered distro mirror info: {'primary': 'http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/', 'security': 'http://security.ubuntu.com/ubuntu'} 2024-12-19 07:27:44,596 - cc_apt_configure.py[DEBUG]: Apt Mirror info: {'primary': 'http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/', 'security': 'http://security.ubuntu.com/ubuntu', 'PRIMARY': 'http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/', 'SECURITY': 'http://security.ubuntu.com/ubuntu', 'MIRROR': 'http://ap-southeast-1.ec2.archive.ubuntu.com/ubuntu/'} 2024-12-19 07:27:44,610 - cc_apt_configure.py[INFO]: No custom template provided, fall back to builtin 2024-12-19 07:27:44,611 - util.py[DEBUG]: Reading from /etc/cloud/templates/sources.list.ubuntu.deb822.tmpl (quiet=False) 2024-12-19 07:27:44,617 - util.py[DEBUG]: Read 2991 bytes from /etc/cloud/templates/sources.list.ubuntu.deb822.tmpl 2024-12-19 07:27:44,630 - util.py[DEBUG]: Writing to /etc/apt/sources.list.d/ubuntu.sources - wb: [644] 3008 bytes 2024-12-19 07:27:44,633 - util.py[DEBUG]: Reading from /etc/apt/sources.list (quiet=False) 2024-12-19 07:27:44,634 - util.py[DEBUG]: Read 270 bytes from /etc/apt/sources.list 2024-12-19 07:27:44,639 - handlers.py[DEBUG]: finish: modules-config/config-apt_configure: SUCCESS: config-apt_configure ran successfully 2024-12-19 07:27:44,639 - modules.py[DEBUG]: Running module byobu () with frequency once-per-instance 2024-12-19 07:27:44,639 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance 2024-12-19 07:27:44,640 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_byobu - wb: [644] 23 bytes 2024-12-19 07:27:44,640 - helpers.py[DEBUG]: Running config-byobu using lock () 2024-12-19 07:27:44,641 - cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found 2024-12-19 07:27:44,641 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu ran successfully 2024-12-19 07:27:44,642 - main.py[DEBUG]: Ran 5 modules with 0 failures 2024-12-19 07:27:44,642 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:44,642 - util.py[DEBUG]: Read 11 bytes from /proc/uptime 2024-12-19 07:27:44,642 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp2puvwt1p) - w: [644] 500 bytes/chars 2024-12-19 07:27:44,642 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:44,642 - util.py[DEBUG]: Read 11 bytes from /proc/uptime 2024-12-19 07:27:44,642 - util.py[DEBUG]: cloud-init mode 'modules' took 0.379 seconds (0.38) 2024-12-19 07:27:44,642 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config 2024-12-19 07:27:45,410 - util.py[DEBUG]: Cloud-init v. 24.3.1-0ubuntu0~24.04.2 running 'modules:final' at Thu, 19 Dec 2024 07:27:45 +0000. Up 13.46 seconds. 2024-12-19 07:27:45,411 - stages.py[DEBUG]: Using distro class 2024-12-19 07:27:45,412 - main.py[INFO]: PID [1] started cloud-init 'modules:final'. 2024-12-19 07:27:45,448 - modules.py[INFO]: Skipping modules 'package_update_upgrade_install,fan,landscape,lxd,ubuntu_drivers,write_files_deferred,puppet,chef,ansible,mcollective,salt_minion,phone_home,power_state_change' because no applicable config is provided. 2024-12-19 07:27:45,448 - modules.py[DEBUG]: Running module reset_rmc () with frequency once-per-instance 2024-12-19 07:27:45,448 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance 2024-12-19 07:27:45,449 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_reset_rmc - wb: [644] 24 bytes 2024-12-19 07:27:45,449 - helpers.py[DEBUG]: Running config-reset_rmc using lock () 2024-12-19 07:27:45,450 - cc_reset_rmc.py[DEBUG]: module disabled, RSCT_PATH not present 2024-12-19 07:27:45,451 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc ran successfully 2024-12-19 07:27:45,451 - modules.py[DEBUG]: Running module scripts_vendor () with frequency once-per-instance 2024-12-19 07:27:45,451 - handlers.py[DEBUG]: start: modules-final/config-scripts_vendor: running config-scripts_vendor with frequency once-per-instance 2024-12-19 07:27:45,451 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_scripts_vendor - wb: [644] 24 bytes 2024-12-19 07:27:45,451 - helpers.py[DEBUG]: Running config-scripts_vendor using lock () 2024-12-19 07:27:45,451 - handlers.py[DEBUG]: finish: modules-final/config-scripts_vendor: SUCCESS: config-scripts_vendor ran successfully 2024-12-19 07:27:45,451 - modules.py[DEBUG]: Running module scripts_per_once () with frequency once 2024-12-19 07:27:45,451 - handlers.py[DEBUG]: start: modules-final/config-scripts_per_once: running config-scripts_per_once with frequency once 2024-12-19 07:27:45,451 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 24 bytes 2024-12-19 07:27:45,452 - helpers.py[DEBUG]: Running config-scripts_per_once using lock () 2024-12-19 07:27:45,452 - handlers.py[DEBUG]: finish: modules-final/config-scripts_per_once: SUCCESS: config-scripts_per_once ran successfully 2024-12-19 07:27:45,452 - modules.py[DEBUG]: Running module scripts_per_boot () with frequency always 2024-12-19 07:27:45,452 - handlers.py[DEBUG]: start: modules-final/config-scripts_per_boot: running config-scripts_per_boot with frequency always 2024-12-19 07:27:45,452 - helpers.py[DEBUG]: Running config-scripts_per_boot using lock () 2024-12-19 07:27:45,452 - handlers.py[DEBUG]: finish: modules-final/config-scripts_per_boot: SUCCESS: config-scripts_per_boot ran successfully 2024-12-19 07:27:45,452 - modules.py[DEBUG]: Running module scripts_per_instance () with frequency once-per-instance 2024-12-19 07:27:45,453 - handlers.py[DEBUG]: start: modules-final/config-scripts_per_instance: running config-scripts_per_instance with frequency once-per-instance 2024-12-19 07:27:45,453 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_scripts_per_instance - wb: [644] 24 bytes 2024-12-19 07:27:45,453 - helpers.py[DEBUG]: Running config-scripts_per_instance using lock () 2024-12-19 07:27:45,453 - handlers.py[DEBUG]: finish: modules-final/config-scripts_per_instance: SUCCESS: config-scripts_per_instance ran successfully 2024-12-19 07:27:45,453 - modules.py[DEBUG]: Running module scripts_user () with frequency once-per-instance 2024-12-19 07:27:45,453 - handlers.py[DEBUG]: start: modules-final/config-scripts_user: running config-scripts_user with frequency once-per-instance 2024-12-19 07:27:45,453 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_scripts_user - wb: [644] 24 bytes 2024-12-19 07:27:45,454 - helpers.py[DEBUG]: Running config-scripts_user using lock () 2024-12-19 07:27:45,454 - subp.py[DEBUG]: Running command ['/var/lib/cloud/instance/scripts/part-001'] with allowed return codes [0] (shell=False, capture=False) 2024-12-19 07:27:45,456 - handlers.py[DEBUG]: finish: modules-final/config-scripts_user: SUCCESS: config-scripts_user ran successfully 2024-12-19 07:27:45,456 - modules.py[DEBUG]: Running module ssh_authkey_fingerprints () with frequency once-per-instance 2024-12-19 07:27:45,456 - handlers.py[DEBUG]: start: modules-final/config-ssh_authkey_fingerprints: running config-ssh_authkey_fingerprints with frequency once-per-instance 2024-12-19 07:27:45,457 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_ssh_authkey_fingerprints - wb: [644] 24 bytes 2024-12-19 07:27:45,457 - helpers.py[DEBUG]: Running config-ssh_authkey_fingerprints using lock () 2024-12-19 07:27:45,458 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2024-12-19 07:27:45,458 - util.py[DEBUG]: Read 3255 bytes from /etc/ssh/sshd_config 2024-12-19 07:27:45,458 - util.py[DEBUG]: Reading from /home/ubuntu/.ssh/authorized_keys (quiet=False) 2024-12-19 07:27:45,458 - util.py[DEBUG]: Read 409 bytes from /home/ubuntu/.ssh/authorized_keys 2024-12-19 07:27:45,471 - handlers.py[DEBUG]: finish: modules-final/config-ssh_authkey_fingerprints: SUCCESS: config-ssh_authkey_fingerprints ran successfully 2024-12-19 07:27:45,471 - modules.py[DEBUG]: Running module keys_to_console () with frequency once-per-instance 2024-12-19 07:27:45,471 - handlers.py[DEBUG]: start: modules-final/config-keys_to_console: running config-keys_to_console with frequency once-per-instance 2024-12-19 07:27:45,471 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_keys_to_console - wb: [644] 24 bytes 2024-12-19 07:27:45,472 - helpers.py[DEBUG]: Running config-keys_to_console using lock () 2024-12-19 07:27:45,472 - subp.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', ''] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:45,516 - handlers.py[DEBUG]: finish: modules-final/config-keys_to_console: SUCCESS: config-keys_to_console ran successfully 2024-12-19 07:27:45,516 - modules.py[DEBUG]: Running module install_hotplug () with frequency once-per-instance 2024-12-19 07:27:45,516 - handlers.py[DEBUG]: start: modules-final/config-install_hotplug: running config-install_hotplug with frequency once-per-instance 2024-12-19 07:27:45,516 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-08df9382ffdaad8f0/sem/config_install_hotplug - wb: [644] 24 bytes 2024-12-19 07:27:45,516 - helpers.py[DEBUG]: Running config-install_hotplug using lock () 2024-12-19 07:27:45,517 - util.py[DEBUG]: Reading from /var/lib/cloud/hotplug.enabled (quiet=False) 2024-12-19 07:27:45,517 - util.py[DEBUG]: File not found: /var/lib/cloud/hotplug.enabled 2024-12-19 07:27:45,517 - stages.py[DEBUG]: Allowed events: {: {, }} 2024-12-19 07:27:45,517 - stages.py[DEBUG]: Event Allowed: scope=network EventType=hotplug 2024-12-19 07:27:45,517 - cc_install_hotplug.py[INFO]: Installing hotplug. 2024-12-19 07:27:45,517 - util.py[DEBUG]: Writing to /etc/udev/rules.d/90-cloud-init-hook-hotplug.rules - wb: [644] 288 bytes 2024-12-19 07:27:45,517 - subp.py[DEBUG]: Running command ['udevadm', 'control', '--reload-rules'] with allowed return codes [0] (shell=False, capture=True) 2024-12-19 07:27:45,537 - handlers.py[DEBUG]: finish: modules-final/config-install_hotplug: SUCCESS: config-install_hotplug ran successfully 2024-12-19 07:27:45,537 - modules.py[DEBUG]: Running module final_message () with frequency always 2024-12-19 07:27:45,537 - handlers.py[DEBUG]: start: modules-final/config-final_message: running config-final_message with frequency always 2024-12-19 07:27:45,537 - helpers.py[DEBUG]: Running config-final_message using lock () 2024-12-19 07:27:45,537 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:45,537 - util.py[DEBUG]: Read 11 bytes from /proc/uptime 2024-12-19 07:27:45,541 - util.py[DEBUG]: Cloud-init v. 24.3.1-0ubuntu0~24.04.2 finished at Thu, 19 Dec 2024 07:27:45 +0000. Datasource DataSourceEc2Local. Up 13.62 seconds 2024-12-19 07:27:45,541 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 69 bytes 2024-12-19 07:27:45,541 - handlers.py[DEBUG]: finish: modules-final/config-final_message: SUCCESS: config-final_message ran successfully 2024-12-19 07:27:45,543 - main.py[DEBUG]: Ran 10 modules with 0 failures 2024-12-19 07:27:45,543 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:45,543 - util.py[DEBUG]: Read 11 bytes from /proc/uptime 2024-12-19 07:27:45,544 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpwbchtlvt) - w: [644] 502 bytes/chars 2024-12-19 07:27:45,544 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmp41esj26m) - w: [644] 69 bytes/chars 2024-12-19 07:27:45,544 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' 2024-12-19 07:27:45,544 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2024-12-19 07:27:45,544 - util.py[DEBUG]: Read 11 bytes from /proc/uptime 2024-12-19 07:27:45,544 - util.py[DEBUG]: cloud-init mode 'modules' took 0.188 seconds (0.19) 2024-12-19 07:27:45,544 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final