-
-
Save akutz/0944edd37c094030c2b57556add1cbdd to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'init-local' at Tue, 24 Aug 2021 20:18:32 +0000. Up 10.72 seconds. | |
Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'init' at Tue, 24 Aug 2021 20:18:34 +0000. Up 13.17 seconds. | |
ci-info: ++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ | |
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+ | |
ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | | |
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+ | |
ci-info: | ens32 | True | 192.168.45.6 | 255.255.255.0 | global | 00:0c:29:e7:e7:1c | | |
ci-info: | ens32 | True | fe80::20c:29ff:fee7:e71c/64 | . | link | 00:0c:29:e7:e7:1c | | |
ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | | |
ci-info: | lo | True | ::1/128 | . | host | . | | |
ci-info: +--------+------+-----------------------------+---------------+--------+-------------------+ | |
ci-info: +++++++++++++++++++++++++++++++Route IPv4 info+++++++++++++++++++++++++++++++ | |
ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ | |
ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | | |
ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ | |
ci-info: | 0 | 0.0.0.0 | 192.168.45.1 | 0.0.0.0 | ens32 | UG | | |
ci-info: | 1 | 192.168.45.0 | 0.0.0.0 | 255.255.255.0 | ens32 | U | | |
ci-info: | 2 | 192.168.45.1 | 0.0.0.0 | 255.255.255.255 | ens32 | UH | | |
ci-info: +-------+--------------+--------------+-----------------+-----------+-------+ | |
ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ | |
ci-info: +-------+-------------+---------+-----------+-------+ | |
ci-info: | Route | Destination | Gateway | Interface | Flags | | |
ci-info: +-------+-------------+---------+-----------+-------+ | |
ci-info: | 1 | fe80::/64 | :: | ens32 | U | | |
ci-info: | 3 | local | :: | ens32 | U | | |
ci-info: | 4 | multicast | :: | ens32 | U | | |
ci-info: +-------+-------------+---------+-----------+-------+ | |
Generating public/private rsa key pair. | |
Your identification has been saved in /etc/ssh/ssh_host_rsa_key | |
Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub | |
The key fingerprint is: | |
SHA256:3lDd2w7ZcmUu3WakCwnjRWO2JpHnuoS1Qw1BWxouA5E root@cloud | |
The key's randomart image is: | |
+---[RSA 3072]----+ | |
| oo .=o* | | |
| E. .o@.+ | | |
| o O== . +| | |
| =o*o. @o| | |
| S+.oo B X| | |
| ..o= . X | | |
| ...o . .| | |
| . | | |
| | | |
+----[SHA256]-----+ | |
Generating public/private dsa key pair. | |
Your identification has been saved in /etc/ssh/ssh_host_dsa_key | |
Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub | |
The key fingerprint is: | |
SHA256:SMZa1elLUZ/EQmbInr+GaDYELwxwuFV50pFUG/PtpZs root@cloud | |
The key's randomart image is: | |
+---[DSA 1024]----+ | |
| . ..++*+==.. | | |
| o o.o.+ *B.+.. | | |
| = =o o.o..+ .| | |
| . .=.. = . o | | |
| .o.oS. o o | | |
| o o . . o | | |
| o . . .E | | |
| = . o | | |
| o . . | | |
+----[SHA256]-----+ | |
Generating public/private ecdsa key pair. | |
Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key | |
Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub | |
The key fingerprint is: | |
SHA256:RJw4/QggUo4hn7vINxgNDnuUO0NueVn6OwIfOFqcUk4 root@cloud | |
The key's randomart image is: | |
+---[ECDSA 256]---+ | |
|+.o .. +.. | | |
|.* + +.+ | | |
|o.B .o.o | | |
|o=E+ + .. . | | |
|.BX++ S | | |
|o+@=.. | | |
|.*.* .. | | |
|. . + .. | | |
| ... | | |
+----[SHA256]-----+ | |
Generating public/private ed25519 key pair. | |
Your identification has been saved in /etc/ssh/ssh_host_ed25519_key | |
Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub | |
The key fingerprint is: | |
SHA256:Nkl5jvA0zbVzNgqlVNI5bSQ59cqD5ImwFyFn7RhkKQA root@cloud | |
The key's randomart image is: | |
+--[ED25519 256]--+ | |
| E.....O==*o | | |
| .Xo*Boo. | | |
| . *.B++++ .| | |
| = O.*.O o | | |
| S + = + | | |
| . o . | | |
| | | |
| | | |
| | | |
+----[SHA256]-----+ | |
Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'modules:config' at Tue, 24 Aug 2021 20:18:39 +0000. Up 18.15 seconds. | |
2021-08-24 20:18:40,925 INFO [0] SSH keys [Authorized] | |
2021-08-24 20:18:40,925 ERROR No matching keys found for [None] | |
2021-08-24 20:18:40,937 - util.py[WARNING]: Failed to run command to import mandy SSH ids | |
2021-08-24 20:18:40,939 - util.py[WARNING]: ssh-import-id failed for: mandy ['None'] | |
2021-08-24 20:18:40,941 - util.py[WARNING]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) failed | |
Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'modules:final' at Tue, 24 Aug 2021 20:18:41 +0000. Up 19.78 seconds. | |
2021-08-24 20:18:41,435 - util.py[WARNING]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) failed | |
2021-08-24 20:18:41,469 - cc_keys_to_console.py[WARNING]: Writing keys to the system console failed! | |
2021-08-24 20:18:41,469 - util.py[WARNING]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) failed | |
Cloud-init v. 21.3-1-g6803368d-0ubuntu1 finished at Tue, 24 Aug 2021 20:18:41 +0000. Datasource DataSourceVMware. Up 19.88 seconds |
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
2021-08-24 20:18:32,337 - util.py[DEBUG]: Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'init-local' at Tue, 24 Aug 2021 20:18:32 +0000. Up 10.72 seconds. | |
2021-08-24 20:18:32,337 - main.py[DEBUG]: No kernel command line url found. | |
2021-08-24 20:18:32,338 - main.py[DEBUG]: Closing stdin. | |
2021-08-24 20:18:32,339 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Writing to /var/lib/cloud/data/python-version - wb: [644] 3 bytes | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net | |
2021-08-24 20:18:32,344 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2021-08-24 20:18:32,344 - stages.py[DEBUG]: no cache found | |
2021-08-24 20:18:32,344 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found | |
2021-08-24 20:18:32,344 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2021-08-24 20:18:32,348 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:32,348 - __init__.py[DEBUG]: Looking for data source in: ['VMware', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] | |
2021-08-24 20:18:32,354 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceVMware'] | |
2021-08-24 20:18:32,354 - handlers.py[DEBUG]: start: init-local/search-VMware: searching for local data from DataSourceVMware | |
2021-08-24 20:18:32,354 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceVMware.DataSourceVMware'> | |
2021-08-24 20:18:32,354 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: boot-new-instance | |
2021-08-24 20:18:32,354 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_name | |
2021-08-24 20:18:32,354 - DataSourceVMware.py[DEBUG]: Getting guestinfo value for key metadata | |
2021-08-24 20:18:32,354 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-get guestinfo.metadata'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,360 - DataSourceVMware.py[DEBUG]: Getting guestinfo value for key metadata.encoding | |
2021-08-24 20:18:32,360 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-get guestinfo.metadata.encoding'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,362 - DataSourceVMware.py[DEBUG]: Getting encoded data for key=guestinfo.metadata, enc=base64 | |
2021-08-24 20:18:32,362 - DataSourceVMware.py[DEBUG]: Decoding base64 format guestinfo.metadata | |
2021-08-24 20:18:32,362 - DataSourceVMware.py[DEBUG]: Getting guestinfo value for key userdata | |
2021-08-24 20:18:32,362 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-get guestinfo.userdata'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,364 - DataSourceVMware.py[DEBUG]: Getting guestinfo value for key userdata.encoding | |
2021-08-24 20:18:32,364 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-get guestinfo.userdata.encoding'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,369 - DataSourceVMware.py[DEBUG]: Getting encoded data for key=guestinfo.userdata, enc=base64 | |
2021-08-24 20:18:32,369 - DataSourceVMware.py[DEBUG]: Decoding base64 format guestinfo.userdata | |
2021-08-24 20:18:32,369 - DataSourceVMware.py[DEBUG]: Getting guestinfo value for key vendordata | |
2021-08-24 20:18:32,369 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-get guestinfo.vendordata'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,371 - DataSourceVMware.py[DEBUG]: No value found for key vendordata | |
2021-08-24 20:18:32,371 - DataSourceVMware.py[INFO]: using data access method guestinfo (guestinfo.metadata) | |
2021-08-24 20:18:32,371 - util.py[DEBUG]: Attempting to load yaml from string of length 135 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,372 - DataSourceVMware.py[DEBUG]: network data copied to 'config' key | |
2021-08-24 20:18:32,372 - DataSourceVMware.py[DEBUG]: network data {'config': {'version': 2, 'ethernets': {'nics': {'match': {'name': 'ens*'}, 'dhcp4': True}}}} | |
2021-08-24 20:18:32,373 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpvz_ejzyy) - w: [600] 4792 bytes/chars | |
2021-08-24 20:18:32,373 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpgbym7qeu) - w: [644] 1562 bytes/chars | |
2021-08-24 20:18:32,373 - handlers.py[DEBUG]: finish: init-local/search-VMware: SUCCESS: found local data from DataSourceVMware | |
2021-08-24 20:18:32,373 - stages.py[INFO]: Loaded datasource DataSourceVMware - DataSourceVMware | |
2021-08-24 20:18:32,373 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2021-08-24 20:18:32,373 - util.py[DEBUG]: Read 3764 bytes from /etc/cloud/cloud.cfg | |
2021-08-24 20:18:32,373 - util.py[DEBUG]: Attempting to load yaml from string of length 3764 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,380 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2021-08-24 20:18:32,380 - util.py[DEBUG]: Read 309 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2021-08-24 20:18:32,380 - util.py[DEBUG]: Attempting to load yaml from string of length 309 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,389 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2021-08-24 20:18:32,389 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2021-08-24 20:18:32,389 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2021-08-24 20:18:32,391 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/cloud' | |
2021-08-24 20:18:32,392 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/cloud/datasource (quiet=False) | |
2021-08-24 20:18:32,392 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/datasource - wb: [644] 35 bytes | |
2021-08-24 20:18:32,392 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 35 bytes | |
2021-08-24 20:18:32,392 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2021-08-24 20:18:32,392 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID | |
2021-08-24 20:18:32,392 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 6 bytes | |
2021-08-24 20:18:32,393 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 6 bytes | |
2021-08-24 20:18:32,393 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes | |
2021-08-24 20:18:32,393 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 6596 bytes | |
2021-08-24 20:18:32,393 - main.py[DEBUG]: [local] init will now be targeting instance id: cloud. new=True | |
2021-08-24 20:18:32,394 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2021-08-24 20:18:32,394 - util.py[DEBUG]: Read 3764 bytes from /etc/cloud/cloud.cfg | |
2021-08-24 20:18:32,394 - util.py[DEBUG]: Attempting to load yaml from string of length 3764 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,406 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2021-08-24 20:18:32,406 - util.py[DEBUG]: Read 309 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2021-08-24 20:18:32,406 - util.py[DEBUG]: Attempting to load yaml from string of length 309 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,407 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2021-08-24 20:18:32,407 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2021-08-24 20:18:32,408 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,409 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2021-08-24 20:18:32,409 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg | |
2021-08-24 20:18:32,409 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,410 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:32,410 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2021-08-24 20:18:32,410 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:32,411 - cc_set_hostname.py[DEBUG]: Setting the hostname to cloud (cloud) | |
2021-08-24 20:18:32,411 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) | |
2021-08-24 20:18:32,411 - util.py[DEBUG]: Read 7 bytes from /etc/hostname | |
2021-08-24 20:18:32,411 - util.py[DEBUG]: Writing to /etc/hostname - wb: [644] 6 bytes | |
2021-08-24 20:18:32,413 - __init__.py[DEBUG]: Non-persistently setting the system hostname to cloud | |
2021-08-24 20:18:32,413 - subp.py[DEBUG]: Running command ['hostname', 'cloud'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,415 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmpweqiciyw) - w: [644] 43 bytes/chars | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Reading from /sys/class/net/ens32/address (quiet=False) | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens32/address | |
2021-08-24 20:18:32,416 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:32,416 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2021-08-24 20:18:32,416 - util.py[DEBUG]: Reading from /sys/class/net/ens32/address (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens32/address | |
2021-08-24 20:18:32,417 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:32,417 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2021-08-24 20:18:32,417 - __init__.py[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/ens32/addr_assign_type (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens32/addr_assign_type | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/ens32/uevent (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 26 bytes from /sys/class/net/ens32/uevent | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/ens32/address (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens32/address | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/ens32/device/device (quiet=False) | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens32/device/device | |
2021-08-24 20:18:32,417 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) | |
2021-08-24 20:18:32,418 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type | |
2021-08-24 20:18:32,418 - util.py[DEBUG]: Reading from /sys/class/net/ens32/type (quiet=False) | |
2021-08-24 20:18:32,418 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens32/type | |
2021-08-24 20:18:32,418 - networking.py[DEBUG]: net: all expected physical devices present | |
2021-08-24 20:18:32,418 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'nics': {'match': {'name': 'ens*'}, 'dhcp4': True}}} | |
2021-08-24 20:18:32,418 - __init__.py[DEBUG]: no interfaces to rename | |
2021-08-24 20:18:32,418 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'version': 2, 'ethernets': {'nics': {'match': {'name': 'ens*'}, 'dhcp4': True}}} | |
2021-08-24 20:18:32,418 - util.py[DEBUG]: Writing to /run/cloud-init/sem/apply_network_config.once - wb: [644] 24 bytes | |
2021-08-24 20:18:32,418 - network_state.py[DEBUG]: NetworkState Version2: missing "macaddress" info in config entry: nics: {'match': {'name': 'ens*'}, 'dhcp4': True} | |
2021-08-24 20:18:32,418 - network_state.py[DEBUG]: v2(ethernets) -> v1(physical): | |
{'type': 'physical', 'name': 'nics', 'mac_address': None, 'match': {'name': 'ens*'}, 'subnets': [{'type': 'dhcp4'}]} | |
2021-08-24 20:18:32,419 - network_state.py[DEBUG]: v2_common: handling config: | |
{'nics': {'match': {'name': 'ens*'}, 'dhcp4': True}} | |
2021-08-24 20:18:32,427 - __init__.py[DEBUG]: Selected renderer 'netplan' from priority list: ['netplan', 'eni', 'sysconfig'] | |
2021-08-24 20:18:32,427 - netplan.py[DEBUG]: V2 to V2 passthrough | |
2021-08-24 20:18:32,427 - util.py[DEBUG]: Writing to /etc/netplan/50-cloud-init.yaml - wb: [644] 435 bytes | |
2021-08-24 20:18:32,427 - subp.py[DEBUG]: Running command ['netplan', 'generate'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,589 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/lo'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,600 - subp.py[DEBUG]: Running command ['udevadm', 'test-builtin', 'net_setup_link', '/sys/class/net/ens32'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:32,604 - main.py[DEBUG]: [local] Exiting. datasource DataSourceVMware not in local mode. | |
2021-08-24 20:18:32,604 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpn7ub4fqw) - w: [644] 488 bytes/chars | |
2021-08-24 20:18:32,604 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2021-08-24 20:18:32,605 - util.py[DEBUG]: Read 11 bytes from /proc/uptime | |
2021-08-24 20:18:32,605 - util.py[DEBUG]: cloud-init mode 'init' took 0.300 seconds (0.30) | |
2021-08-24 20:18:32,605 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources | |
2021-08-24 20:18:34,777 - util.py[DEBUG]: Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'init' at Tue, 24 Aug 2021 20:18:34 +0000. Up 13.17 seconds. | |
2021-08-24 20:18:34,777 - main.py[DEBUG]: No kernel command line url found. | |
2021-08-24 20:18:34,777 - main.py[DEBUG]: Closing stdin. | |
2021-08-24 20:18:34,778 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes | |
2021-08-24 20:18:34,778 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 104:4 | |
2021-08-24 20:18:34,778 - subp.py[DEBUG]: Running command ['ip', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:34,790 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:34,792 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) | |
2021-08-24 20:18:34,795 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. | |
2021-08-24 20:18:34,795 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. | |
2021-08-24 20:18:34,796 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] | |
2021-08-24 20:18:34,796 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) | |
2021-08-24 20:18:34,796 - util.py[DEBUG]: Read 6596 bytes from /var/lib/cloud/instance/obj.pkl | |
2021-08-24 20:18:34,798 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) | |
2021-08-24 20:18:34,798 - util.py[DEBUG]: Read 6 bytes from /run/cloud-init/.instance-id | |
2021-08-24 20:18:34,798 - stages.py[DEBUG]: restored from cache with run check: DataSourceVMware | |
2021-08-24 20:18:34,798 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceVMware | |
2021-08-24 20:18:34,798 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,798 - util.py[DEBUG]: Read 3764 bytes from /etc/cloud/cloud.cfg | |
2021-08-24 20:18:34,798 - util.py[DEBUG]: Attempting to load yaml from string of length 3764 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,805 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2021-08-24 20:18:34,805 - util.py[DEBUG]: Read 309 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2021-08-24 20:18:34,805 - util.py[DEBUG]: Attempting to load yaml from string of length 309 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,806 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2021-08-24 20:18:34,806 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2021-08-24 20:18:34,806 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,809 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance | |
2021-08-24 20:18:34,810 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/cloud' | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/cloud/datasource (quiet=False) | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Read 35 bytes from /var/lib/cloud/instances/cloud/datasource | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/datasource - wb: [644] 35 bytes | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 35 bytes | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Read 6 bytes from /var/lib/cloud/data/instance-id | |
2021-08-24 20:18:34,811 - stages.py[DEBUG]: previous iid found to be cloud | |
2021-08-24 20:18:34,811 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 6 bytes | |
2021-08-24 20:18:34,812 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 6 bytes | |
2021-08-24 20:18:34,812 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 6 bytes | |
2021-08-24 20:18:34,812 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 6599 bytes | |
2021-08-24 20:18:34,813 - main.py[DEBUG]: [net] init will now be targeting instance id: cloud. new=False | |
2021-08-24 20:18:34,813 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,813 - util.py[DEBUG]: Read 3764 bytes from /etc/cloud/cloud.cfg | |
2021-08-24 20:18:34,813 - util.py[DEBUG]: Attempting to load yaml from string of length 3764 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,819 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2021-08-24 20:18:34,819 - util.py[DEBUG]: Read 309 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2021-08-24 20:18:34,819 - util.py[DEBUG]: Attempting to load yaml from string of length 309 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,821 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2021-08-24 20:18:34,821 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2021-08-24 20:18:34,821 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,822 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,822 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg | |
2021-08-24 20:18:34,822 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,822 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,823 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2021-08-24 20:18:34,823 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) | |
2021-08-24 20:18:34,823 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address | |
2021-08-24 20:18:34,823 - util.py[DEBUG]: Reading from /sys/class/net/ens32/address (quiet=False) | |
2021-08-24 20:18:34,823 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens32/address | |
2021-08-24 20:18:34,823 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:34,823 - DataSourceVMware.py[DEBUG]: using metadata network config | |
2021-08-24 20:18:34,824 - stages.py[DEBUG]: Allowed events: {<EventScope.NETWORK: 'network'>: {<EventType.BOOT_NEW_INSTANCE: 'boot-new-instance'>}} | |
2021-08-24 20:18:34,824 - stages.py[DEBUG]: Event Denied: scopes=['network'] EventType=boot-legacy | |
2021-08-24 20:18:34,824 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update allowed | |
2021-08-24 20:18:34,824 - stages.py[DEBUG]: applying net config names for {'version': 2, 'ethernets': {'nics': {'match': {'name': 'ens*'}, 'dhcp4': True}}} | |
2021-08-24 20:18:34,824 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:34,824 - __init__.py[DEBUG]: no interfaces to rename | |
2021-08-24 20:18:34,824 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource | |
2021-08-24 20:18:34,948 - DataSourceVMware.py[DEBUG]: waiting on network complete | |
2021-08-24 20:18:34,948 - DataSourceVMware.py[INFO]: got host-info: {'network': {'interfaces': {'by-mac': OrderedDict([('00:0c:29:e7:e7:1c', {'ipv4': [{'addr': '192.168.45.6', 'netmask': '255.255.255.0', 'broadcast': '192.168.45.255'}], 'ipv6': []})]), 'by-ipv4': OrderedDict([('192.168.45.6', {'netmask': '255.255.255.0', 'broadcast': '192.168.45.255', 'mac': '00:0c:29:e7:e7:1c'})]), 'by-ipv6': OrderedDict()}}, 'hostname': 'cloud.vmware.com', 'local-hostname': 'cloud.vmware.com', 'local_hostname': 'cloud.vmware.com', 'local-ipv4': '192.168.45.6', 'local-ipv6': 'fe80::20c:29ff:fee7:e71c%ens32'} | |
2021-08-24 20:18:34,948 - DataSourceVMware.py[DEBUG]: Setting guestinfo key=local-ipv4 to value=192.168.45.6 | |
2021-08-24 20:18:34,948 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-set guestinfo.local-ipv4 192.168.45.6'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:34,959 - DataSourceVMware.py[INFO]: advertised local ipv4 address 192.168.45.6 in guestinfo | |
2021-08-24 20:18:34,959 - DataSourceVMware.py[DEBUG]: Setting guestinfo key=local-ipv6 to value=fe80::20c:29ff:fee7:e71c%ens32 | |
2021-08-24 20:18:34,959 - subp.py[DEBUG]: Running command ['/usr/bin/vmware-rpctool', 'info-set guestinfo.local-ipv6 fe80::20c:29ff:fee7:e71c%ens32'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:34,967 - DataSourceVMware.py[INFO]: advertised local ipv6 address fe80::20c:29ff:fee7:e71c%ens32 in guestinfo | |
2021-08-24 20:18:34,969 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpct9ufvk6) - w: [600] 5398 bytes/chars | |
2021-08-24 20:18:34,969 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmp798v4oie) - w: [644] 2168 bytes/chars | |
2021-08-24 20:18:34,969 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource | |
2021-08-24 20:18:34,969 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/user-data.txt - wb: [600] 951 bytes | |
2021-08-24 20:18:34,970 - util.py[DEBUG]: Attempting to load yaml from string of length 951 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,973 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/user-data.txt.i - wb: [600] 1256 bytes | |
2021-08-24 20:18:34,973 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/vendor-data.txt - wb: [600] 0 bytes | |
2021-08-24 20:18:34,974 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/vendor-data.txt.i - wb: [600] 308 bytes | |
2021-08-24 20:18:34,974 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/vendor-data2.txt - wb: [600] 0 bytes | |
2021-08-24 20:18:34,975 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/vendor-data2.txt.i - wb: [600] 308 bytes | |
2021-08-24 20:18:34,975 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2021-08-24 20:18:34,975 - util.py[DEBUG]: Read 43 bytes from /var/lib/cloud/data/set-hostname | |
2021-08-24 20:18:34,975 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2021-08-24 20:18:34,976 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/consume_data - wb: [644] 23 bytes | |
2021-08-24 20:18:34,976 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/consume_data'>) | |
2021-08-24 20:18:34,976 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data | |
2021-08-24 20:18:34,976 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] | |
2021-08-24 20:18:34,976 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] | |
2021-08-24 20:18:34,977 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] | |
2021-08-24 20:18:34,977 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] | |
2021-08-24 20:18:34,977 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/cloud-config', 'Content-Disposition': 'attachment; filename="part-001"'} | |
2021-08-24 20:18:34,977 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, part-001, 3) with frequency once-per-instance | |
2021-08-24 20:18:34,977 - util.py[DEBUG]: Attempting to load yaml from string of length 951 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,978 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])] | |
2021-08-24 20:18:34,979 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance | |
2021-08-24 20:18:34,980 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/cloud-config.txt - wb: [600] 992 bytes | |
2021-08-24 20:18:34,980 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,980 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,980 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance | |
2021-08-24 20:18:34,980 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance | |
2021-08-24 20:18:34,980 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data | |
2021-08-24 20:18:34,981 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data | |
2021-08-24 20:18:34,981 - stages.py[DEBUG]: no vendordata from datasource | |
2021-08-24 20:18:34,981 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data | |
2021-08-24 20:18:34,981 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 | |
2021-08-24 20:18:34,981 - stages.py[DEBUG]: no vendordata2 from datasource | |
2021-08-24 20:18:34,981 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 | |
2021-08-24 20:18:34,981 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,981 - util.py[DEBUG]: Read 3764 bytes from /etc/cloud/cloud.cfg | |
2021-08-24 20:18:34,981 - util.py[DEBUG]: Attempting to load yaml from string of length 3764 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,989 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) | |
2021-08-24 20:18:34,989 - util.py[DEBUG]: Read 309 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg | |
2021-08-24 20:18:34,989 - util.py[DEBUG]: Attempting to load yaml from string of length 309 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,990 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) | |
2021-08-24 20:18:34,990 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg | |
2021-08-24 20:18:34,990 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,992 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) | |
2021-08-24 20:18:34,992 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg | |
2021-08-24 20:18:34,992 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,993 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,993 - util.py[DEBUG]: loaded blob returned None, returning default. | |
2021-08-24 20:18:34,993 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2021-08-24 20:18:34,993 - util.py[DEBUG]: Read 992 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2021-08-24 20:18:34,993 - util.py[DEBUG]: Attempting to load yaml from string of length 992 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,995 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) | |
2021-08-24 20:18:34,995 - util.py[DEBUG]: Read 992 bytes from /var/lib/cloud/instance/cloud-config.txt | |
2021-08-24 20:18:34,995 - util.py[DEBUG]: Attempting to load yaml from string of length 992 with allowed root types (<class 'dict'>,) | |
2021-08-24 20:18:34,997 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource | |
2021-08-24 20:18:34,998 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10031 bytes | |
2021-08-24 20:18:34,998 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource | |
2021-08-24 20:18:34,998 - main.py[DEBUG]: no di_report found in config. | |
2021-08-24 20:18:35,018 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:35,018 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_migrator.py'>) with frequency always | |
2021-08-24 20:18:35,018 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always | |
2021-08-24 20:18:35,019 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7effede71550>) | |
2021-08-24 20:18:35,019 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names | |
2021-08-24 20:18:35,019 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully | |
2021-08-24 20:18:35,019 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_seed_random.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,019 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance | |
2021-08-24 20:18:35,019 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_seed_random - wb: [644] 24 bytes | |
2021-08-24 20:18:35,019 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_seed_random'>) | |
2021-08-24 20:18:35,019 - cc_seed_random.py[DEBUG]: no command provided | |
2021-08-24 20:18:35,020 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully | |
2021-08-24 20:18:35,020 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_bootcmd.py'>) with frequency always | |
2021-08-24 20:18:35,020 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always | |
2021-08-24 20:18:35,020 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7effedeb8f70>) | |
2021-08-24 20:18:35,020 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration | |
2021-08-24 20:18:35,020 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully | |
2021-08-24 20:18:35,020 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_write_files.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,020 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance | |
2021-08-24 20:18:35,020 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_write_files - wb: [644] 24 bytes | |
2021-08-24 20:18:35,020 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_write_files'>) | |
2021-08-24 20:18:35,020 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration | |
2021-08-24 20:18:35,020 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully | |
2021-08-24 20:18:35,020 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_growpart.py'>) with frequency always | |
2021-08-24 20:18:35,021 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always | |
2021-08-24 20:18:35,021 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7effedeb89a0>) | |
2021-08-24 20:18:35,021 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/'], 'ignore_growroot_disabled': False} | |
2021-08-24 20:18:35,021 - subp.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Reading from /proc/808/mountinfo (quiet=False) | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Read 3837 bytes from /proc/808/mountinfo | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False) | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:10.0/host31/target31:0:0/31:0:0:0/block/sda/dev (quiet=False) | |
2021-08-24 20:18:35,025 - util.py[DEBUG]: Read 4 bytes from /sys/devices/pci0000:00/0000:00:10.0/host31/target31:0:0/31:0:0:0/block/sda/dev | |
2021-08-24 20:18:35,026 - subp.py[DEBUG]: Running command ['growpart', '--dry-run', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,065 - subp.py[DEBUG]: Running command ['growpart', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,480 - util.py[DEBUG]: resize_devices took 0.455 seconds | |
2021-08-24 20:18:35,480 - cc_growpart.py[INFO]: '/' resized: changed (/dev/sda, 1) from 2244984320 to 10621009408 | |
2021-08-24 20:18:35,480 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully | |
2021-08-24 20:18:35,480 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_resizefs.py'>) with frequency always | |
2021-08-24 20:18:35,481 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always | |
2021-08-24 20:18:35,481 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7effedeb89a0>) | |
2021-08-24 20:18:35,529 - util.py[DEBUG]: Reading from /proc/808/mountinfo (quiet=False) | |
2021-08-24 20:18:35,529 - util.py[DEBUG]: Read 3837 bytes from /proc/808/mountinfo | |
2021-08-24 20:18:35,529 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/sda1 mnt_point=/ path=/ | |
2021-08-24 20:18:35,529 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/sda1 | |
2021-08-24 20:18:35,529 - subp.py[DEBUG]: Running command ('resize2fs', '/dev/sda1') with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,563 - util.py[DEBUG]: Resizing took 0.033 seconds | |
2021-08-24 20:18:35,563 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True) | |
2021-08-24 20:18:35,563 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully | |
2021-08-24 20:18:35,563 - stages.py[DEBUG]: Running module disk_setup (<module 'cloudinit.config.cc_disk_setup' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_disk_setup.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,563 - handlers.py[DEBUG]: start: init-network/config-disk_setup: running config-disk_setup with frequency once-per-instance | |
2021-08-24 20:18:35,564 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_disk_setup - wb: [644] 24 bytes | |
2021-08-24 20:18:35,564 - helpers.py[DEBUG]: Running config-disk_setup using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_disk_setup'>) | |
2021-08-24 20:18:35,564 - handlers.py[DEBUG]: finish: init-network/config-disk_setup: SUCCESS: config-disk_setup ran successfully | |
2021-08-24 20:18:35,564 - stages.py[DEBUG]: Running module mounts (<module 'cloudinit.config.cc_mounts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mounts.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,564 - handlers.py[DEBUG]: start: init-network/config-mounts: running config-mounts with frequency once-per-instance | |
2021-08-24 20:18:35,564 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_mounts - wb: [644] 24 bytes | |
2021-08-24 20:18:35,565 - helpers.py[DEBUG]: Running config-mounts using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_mounts'>) | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: mounts configuration is [] | |
2021-08-24 20:18:35,565 - util.py[DEBUG]: Reading from /etc/fstab (quiet=False) | |
2021-08-24 20:18:35,565 - util.py[DEBUG]: Read 101 bytes from /etc/fstab | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: changed default device swap => None | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: no need to setup swap | |
2021-08-24 20:18:35,565 - cc_mounts.py[DEBUG]: No modifications to fstab needed | |
2021-08-24 20:18:35,565 - handlers.py[DEBUG]: finish: init-network/config-mounts: SUCCESS: config-mounts ran successfully | |
2021-08-24 20:18:35,565 - stages.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_hostname.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,565 - handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance | |
2021-08-24 20:18:35,566 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_set_hostname - wb: [644] 23 bytes | |
2021-08-24 20:18:35,566 - helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_set_hostname'>) | |
2021-08-24 20:18:35,566 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) | |
2021-08-24 20:18:35,566 - util.py[DEBUG]: Read 43 bytes from /var/lib/cloud/data/set-hostname | |
2021-08-24 20:18:35,566 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname | |
2021-08-24 20:18:35,566 - handlers.py[DEBUG]: finish: init-network/config-set_hostname: SUCCESS: config-set_hostname ran successfully | |
2021-08-24 20:18:35,566 - stages.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_hostname.py'>) with frequency always | |
2021-08-24 20:18:35,566 - handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always | |
2021-08-24 20:18:35,566 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7effedeb89a0>) | |
2021-08-24 20:18:35,566 - cc_update_hostname.py[DEBUG]: Updating hostname to cloud (cloud) | |
2021-08-24 20:18:35,566 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False) | |
2021-08-24 20:18:35,566 - util.py[DEBUG]: Read 6 bytes from /etc/hostname | |
2021-08-24 20:18:35,567 - __init__.py[DEBUG]: Attempting to update hostname to cloud in 1 files | |
2021-08-24 20:18:35,567 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False) | |
2021-08-24 20:18:35,567 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [644] 6 bytes | |
2021-08-24 20:18:35,567 - handlers.py[DEBUG]: finish: init-network/config-update_hostname: SUCCESS: config-update_hostname ran successfully | |
2021-08-24 20:18:35,567 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_update_etc_hosts.py'>) with frequency always | |
2021-08-24 20:18:35,567 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always | |
2021-08-24 20:18:35,567 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7effede716a0>) | |
2021-08-24 20:18:35,567 - cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts | |
2021-08-24 20:18:35,567 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully | |
2021-08-24 20:18:35,567 - stages.py[DEBUG]: Running module ca-certs (<module 'cloudinit.config.cc_ca_certs' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ca_certs.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,567 - handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance | |
2021-08-24 20:18:35,567 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ca_certs - wb: [644] 23 bytes | |
2021-08-24 20:18:35,568 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ca_certs'>) | |
2021-08-24 20:18:35,568 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration | |
2021-08-24 20:18:35,568 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully | |
2021-08-24 20:18:35,568 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_rsyslog.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,568 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance | |
2021-08-24 20:18:35,568 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_rsyslog - wb: [644] 24 bytes | |
2021-08-24 20:18:35,568 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_rsyslog'>) | |
2021-08-24 20:18:35,568 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration | |
2021-08-24 20:18:35,568 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully | |
2021-08-24 20:18:35,569 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_users_groups.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,569 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance | |
2021-08-24 20:18:35,569 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_users_groups - wb: [644] 24 bytes | |
2021-08-24 20:18:35,569 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_users_groups'>) | |
2021-08-24 20:18:35,570 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2021-08-24 20:18:35,570 - util.py[DEBUG]: Read 401 bytes from /etc/os-release | |
2021-08-24 20:18:35,570 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2021-08-24 20:18:35,570 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2021-08-24 20:18:35,571 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2021-08-24 20:18:35,571 - util.py[DEBUG]: Read 401 bytes from /etc/os-release | |
2021-08-24 20:18:35,571 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2021-08-24 20:18:35,571 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2021-08-24 20:18:35,572 - subp.py[DEBUG]: Running command ['groupadd', 'wheel'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,581 - __init__.py[INFO]: Created new group wheel | |
2021-08-24 20:18:35,582 - __init__.py[DEBUG]: created group 'wheel' for user 'mandy' | |
2021-08-24 20:18:35,582 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2021-08-24 20:18:35,582 - util.py[DEBUG]: Read 401 bytes from /etc/os-release | |
2021-08-24 20:18:35,582 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2021-08-24 20:18:35,582 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2021-08-24 20:18:35,583 - subp.py[DEBUG]: Running command ['groupadd', 'mandy'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,591 - __init__.py[INFO]: Created new group mandy | |
2021-08-24 20:18:35,591 - __init__.py[DEBUG]: created group 'mandy' for user 'mandy' | |
2021-08-24 20:18:35,591 - __init__.py[DEBUG]: Adding user mandy | |
2021-08-24 20:18:35,591 - subp.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'mandy', '--groups', 'sudo,wheel', '--gid', 'mandy', '-m'] | |
2021-08-24 20:18:35,611 - subp.py[DEBUG]: Running command ['passwd', '-l', 'mandy'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,617 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) | |
2021-08-24 20:18:35,617 - util.py[DEBUG]: Read 755 bytes from /etc/sudoers | |
2021-08-24 20:18:35,618 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 141 bytes | |
2021-08-24 20:18:35,619 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2021-08-24 20:18:35,619 - util.py[DEBUG]: Read 3287 bytes from /etc/ssh/sshd_config | |
2021-08-24 20:18:35,619 - util.py[DEBUG]: Changing the ownership of /home/mandy/.ssh to 1000:1001 | |
2021-08-24 20:18:35,619 - util.py[DEBUG]: Writing to /home/mandy/.ssh/authorized_keys - wb: [600] 0 bytes | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Changing the ownership of /home/mandy/.ssh/authorized_keys to 1000:1001 | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Reading from /home/mandy/.ssh/authorized_keys (quiet=False) | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Read 0 bytes from /home/mandy/.ssh/authorized_keys | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Writing to /home/mandy/.ssh/authorized_keys - wb: [600] 742 bytes | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2021-08-24 20:18:35,620 - util.py[DEBUG]: Read 401 bytes from /etc/os-release | |
2021-08-24 20:18:35,621 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2021-08-24 20:18:35,621 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2021-08-24 20:18:35,621 - __init__.py[DEBUG]: Adding user ubuntu | |
2021-08-24 20:18:35,621 - subp.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'ubuntu', '--comment', 'Ubuntu', '--groups', 'adm,audio,cdrom,dialout,dip,floppy,lxd,netdev,plugdev,sudo,video', '--shell', '/bin/bash', '-m'] | |
2021-08-24 20:18:35,638 - subp.py[DEBUG]: Running command ['passwd', '-l', 'ubuntu'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,643 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) | |
2021-08-24 20:18:35,643 - util.py[DEBUG]: Read 755 bytes from /etc/sudoers | |
2021-08-24 20:18:35,644 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - ab: [None] 55 bytes | |
2021-08-24 20:18:35,644 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully | |
2021-08-24 20:18:35,644 - stages.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh.py'>) with frequency once-per-instance | |
2021-08-24 20:18:35,644 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance | |
2021-08-24 20:18:35,644 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ssh - wb: [644] 24 bytes | |
2021-08-24 20:18:35,644 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ssh'>) | |
2021-08-24 20:18:35,645 - 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) | |
2021-08-24 20:18:35,719 - subp.py[DEBUG]: Running command ['ssh-keygen', '-t', 'dsa', '-N', '', '-f', '/etc/ssh/ssh_host_dsa_key'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:35,761 - 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) | |
2021-08-24 20:18:35,765 - 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) | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Read 172 bytes from /etc/ssh/ssh_host_ecdsa_key.pub | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Read 92 bytes from /etc/ssh/ssh_host_ed25519_key.pub | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) | |
2021-08-24 20:18:35,771 - util.py[DEBUG]: Read 564 bytes from /etc/ssh/ssh_host_rsa_key.pub | |
2021-08-24 20:18:35,772 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2021-08-24 20:18:35,772 - util.py[DEBUG]: Read 3287 bytes from /etc/ssh/sshd_config | |
2021-08-24 20:18:35,772 - util.py[DEBUG]: Changing the ownership of /home/ubuntu/.ssh to 1001:1002 | |
2021-08-24 20:18:35,772 - util.py[DEBUG]: Writing to /home/ubuntu/.ssh/authorized_keys - wb: [600] 0 bytes | |
2021-08-24 20:18:35,772 - util.py[DEBUG]: Changing the ownership of /home/ubuntu/.ssh/authorized_keys to 1001:1002 | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Reading from /home/ubuntu/.ssh/authorized_keys (quiet=False) | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Read 0 bytes from /home/ubuntu/.ssh/authorized_keys | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Writing to /home/ubuntu/.ssh/authorized_keys - wb: [600] 0 bytes | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Read 3287 bytes from /etc/ssh/sshd_config | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 | |
2021-08-24 20:18:35,773 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes | |
2021-08-24 20:18:35,774 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 | |
2021-08-24 20:18:35,774 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False) | |
2021-08-24 20:18:35,774 - util.py[DEBUG]: Read 0 bytes from /root/.ssh/authorized_keys | |
2021-08-24 20:18:35,774 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes | |
2021-08-24 20:18:35,774 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully | |
2021-08-24 20:18:35,774 - main.py[DEBUG]: Ran 15 modules with 0 failures | |
2021-08-24 20:18:35,774 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpzzpul_qe) - w: [644] 514 bytes/chars | |
2021-08-24 20:18:35,775 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2021-08-24 20:18:35,775 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2021-08-24 20:18:35,775 - util.py[DEBUG]: cloud-init mode 'init' took 1.019 seconds (1.01) | |
2021-08-24 20:18:35,775 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources | |
2021-08-24 20:18:39,781 - util.py[DEBUG]: Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'modules:config' at Tue, 24 Aug 2021 20:18:39 +0000. Up 18.15 seconds. | |
2021-08-24 20:18:39,798 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:39,799 - stages.py[DEBUG]: Running module emit_upstart (<module 'cloudinit.config.cc_emit_upstart' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_emit_upstart.py'>) with frequency always | |
2021-08-24 20:18:39,799 - handlers.py[DEBUG]: start: modules-config/config-emit_upstart: running config-emit_upstart with frequency always | |
2021-08-24 20:18:39,799 - helpers.py[DEBUG]: Running config-emit_upstart using lock (<cloudinit.helpers.DummyLock object at 0x7f978c28f5e0>) | |
2021-08-24 20:18:39,799 - cc_emit_upstart.py[DEBUG]: no /sbin/initctl located | |
2021-08-24 20:18:39,799 - cc_emit_upstart.py[DEBUG]: not upstart system, 'emit_upstart' disabled | |
2021-08-24 20:18:39,799 - handlers.py[DEBUG]: finish: modules-config/config-emit_upstart: SUCCESS: config-emit_upstart ran successfully | |
2021-08-24 20:18:39,799 - stages.py[DEBUG]: Running module snap (<module 'cloudinit.config.cc_snap' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_snap.py'>) with frequency once-per-instance | |
2021-08-24 20:18:39,799 - handlers.py[DEBUG]: start: modules-config/config-snap: running config-snap with frequency once-per-instance | |
2021-08-24 20:18:39,799 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_snap - wb: [644] 25 bytes | |
2021-08-24 20:18:39,800 - helpers.py[DEBUG]: Running config-snap using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_snap'>) | |
2021-08-24 20:18:39,800 - cc_snap.py[DEBUG]: Skipping module named snap, no 'snap' key in configuration | |
2021-08-24 20:18:39,800 - handlers.py[DEBUG]: finish: modules-config/config-snap: SUCCESS: config-snap ran successfully | |
2021-08-24 20:18:39,800 - stages.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) with frequency once-per-instance | |
2021-08-24 20:18:39,800 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance | |
2021-08-24 20:18:39,800 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ssh_import_id - wb: [644] 24 bytes | |
2021-08-24 20:18:39,800 - helpers.py[DEBUG]: Running config-ssh-import-id using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ssh_import_id'>) | |
2021-08-24 20:18:39,801 - cc_ssh_import_id.py[DEBUG]: Importing SSH ids for user mandy. | |
2021-08-24 20:18:39,801 - subp.py[DEBUG]: Running command ['sudo', '-Hu', 'mandy', 'ssh-import-id', 'None'] with allowed return codes [0] (shell=False, capture=False) | |
2021-08-24 20:18:40,937 - util.py[WARNING]: Failed to run command to import mandy SSH ids | |
2021-08-24 20:18:40,937 - util.py[DEBUG]: Failed to run command to import mandy SSH ids | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 105, in import_ssh_ids | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3/dist-packages/cloudinit/subp.py", line 293, in subp | |
raise ProcessExecutionError(stdout=out, stderr=err, | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['sudo', '-Hu', 'mandy', 'ssh-import-id', 'None'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2021-08-24 20:18:40,939 - util.py[WARNING]: ssh-import-id failed for: mandy ['None'] | |
2021-08-24 20:18:40,939 - util.py[DEBUG]: ssh-import-id failed for: mandy ['None'] | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 80, in handle | |
import_ssh_ids(import_ids, user, log) | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 108, in import_ssh_ids | |
raise exc | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 105, in import_ssh_ids | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3/dist-packages/cloudinit/subp.py", line 293, in subp | |
raise ProcessExecutionError(stdout=out, stderr=err, | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['sudo', '-Hu', 'mandy', 'ssh-import-id', 'None'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2021-08-24 20:18:40,941 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: FAIL: running config-ssh-import-id with frequency once-per-instance | |
2021-08-24 20:18:40,941 - util.py[WARNING]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) failed | |
2021-08-24 20:18:40,941 - util.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py'>) failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/stages.py", line 965, in _run_modules | |
ran, _r = cc.run(run_name, mod.handle, func_args, | |
File "/usr/lib/python3/dist-packages/cloudinit/cloud.py", line 54, in run | |
return self._runners.run(name, functor, args, freq, clear_on_fail) | |
File "/usr/lib/python3/dist-packages/cloudinit/helpers.py", line 186, in run | |
results = functor(*args) | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 87, in handle | |
raise elist[0] | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 80, in handle | |
import_ssh_ids(import_ids, user, log) | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 108, in import_ssh_ids | |
raise exc | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_import_id.py", line 105, in import_ssh_ids | |
subp.subp(cmd, capture=False) | |
File "/usr/lib/python3/dist-packages/cloudinit/subp.py", line 293, in subp | |
raise ProcessExecutionError(stdout=out, stderr=err, | |
cloudinit.subp.ProcessExecutionError: Unexpected error while running command. | |
Command: ['sudo', '-Hu', 'mandy', 'ssh-import-id', 'None'] | |
Exit code: 1 | |
Reason: - | |
Stdout: - | |
Stderr: - | |
2021-08-24 20:18:40,943 - stages.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_locale.py'>) with frequency once-per-instance | |
2021-08-24 20:18:40,944 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance | |
2021-08-24 20:18:40,944 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_locale - wb: [644] 25 bytes | |
2021-08-24 20:18:40,944 - helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_locale'>) | |
2021-08-24 20:18:40,944 - util.py[DEBUG]: Reading from /etc/default/locale (quiet=False) | |
2021-08-24 20:18:40,945 - util.py[DEBUG]: Read 13 bytes from /etc/default/locale | |
2021-08-24 20:18:40,966 - cc_locale.py[DEBUG]: Setting locale to C.UTF-8 | |
2021-08-24 20:18:40,966 - debian.py[DEBUG]: System has 'LANG=C.UTF-8' requested 'C.UTF-8', skipping regeneration. | |
2021-08-24 20:18:40,966 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully | |
2021-08-24 20:18:40,966 - stages.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_set_passwords.py'>) with frequency once-per-instance | |
2021-08-24 20:18:40,966 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance | |
2021-08-24 20:18:40,966 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_set_passwords - wb: [644] 25 bytes | |
2021-08-24 20:18:40,967 - helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_set_passwords'>) | |
2021-08-24 20:18:40,967 - cc_set_passwords.py[DEBUG]: Leaving SSH config 'PasswordAuthentication' unchanged. ssh_pwauth=None | |
2021-08-24 20:18:40,967 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully | |
2021-08-24 20:18:40,967 - stages.py[DEBUG]: Running module grub-dpkg (<module 'cloudinit.config.cc_grub_dpkg' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_grub_dpkg.py'>) with frequency once-per-instance | |
2021-08-24 20:18:40,967 - handlers.py[DEBUG]: start: modules-config/config-grub-dpkg: running config-grub-dpkg with frequency once-per-instance | |
2021-08-24 20:18:40,967 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_grub_dpkg - wb: [644] 25 bytes | |
2021-08-24 20:18:40,967 - helpers.py[DEBUG]: Running config-grub-dpkg using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_grub_dpkg'>) | |
2021-08-24 20:18:40,967 - subp.py[DEBUG]: Running command ['grub-probe', '-t', 'disk', '/boot'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:40,976 - subp.py[DEBUG]: Running command ['udevadm', 'info', '--root', '--query=symlink', '/dev/sda'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:40,979 - cc_grub_dpkg.py[DEBUG]: considering these device symlinks: /dev/disk/by-path/pci-0000:00:10.0-scsi-0:0:0:0 | |
2021-08-24 20:18:40,980 - cc_grub_dpkg.py[DEBUG]: filtered to these disk/by-id symlinks: | |
2021-08-24 20:18:40,980 - cc_grub_dpkg.py[DEBUG]: selected /dev/sda | |
2021-08-24 20:18:40,980 - cc_grub_dpkg.py[DEBUG]: Setting grub debconf-set-selections with '/dev/sda','false' | |
2021-08-24 20:18:40,980 - subp.py[DEBUG]: Running command ['debconf-set-selections'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:41,105 - handlers.py[DEBUG]: finish: modules-config/config-grub-dpkg: SUCCESS: config-grub-dpkg ran successfully | |
2021-08-24 20:18:41,105 - stages.py[DEBUG]: Running module apt-pipelining (<module 'cloudinit.config.cc_apt_pipelining' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_pipelining.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,105 - handlers.py[DEBUG]: start: modules-config/config-apt-pipelining: running config-apt-pipelining with frequency once-per-instance | |
2021-08-24 20:18:41,105 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_apt_pipelining - wb: [644] 25 bytes | |
2021-08-24 20:18:41,106 - helpers.py[DEBUG]: Running config-apt-pipelining using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_apt_pipelining'>) | |
2021-08-24 20:18:41,106 - handlers.py[DEBUG]: finish: modules-config/config-apt-pipelining: SUCCESS: config-apt-pipelining ran successfully | |
2021-08-24 20:18:41,106 - stages.py[DEBUG]: Running module apt-configure (<module 'cloudinit.config.cc_apt_configure' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_apt_configure.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,106 - handlers.py[DEBUG]: start: modules-config/config-apt-configure: running config-apt-configure with frequency once-per-instance | |
2021-08-24 20:18:41,106 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_apt_configure - wb: [644] 25 bytes | |
2021-08-24 20:18:41,106 - helpers.py[DEBUG]: Running config-apt-configure using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_apt_configure'>) | |
2021-08-24 20:18:41,107 - cc_apt_configure.py[DEBUG]: debconf_selections was not set in config | |
2021-08-24 20:18:41,107 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) | |
2021-08-24 20:18:41,107 - util.py[DEBUG]: Read 401 bytes from /etc/os-release | |
2021-08-24 20:18:41,107 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) | |
2021-08-24 20:18:41,107 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini | |
2021-08-24 20:18:41,107 - cc_apt_configure.py[DEBUG]: handling apt config: {} | |
2021-08-24 20:18:41,108 - subp.py[DEBUG]: Running command ['lsb_release', '--all'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:41,144 - subp.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:41,146 - cc_apt_configure.py[DEBUG]: got primary mirror: None | |
2021-08-24 20:18:41,147 - cc_apt_configure.py[DEBUG]: got security mirror: None | |
2021-08-24 20:18:41,147 - subp.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:41,149 - util.py[DEBUG]: search for mirror in candidates: '[]' | |
2021-08-24 20:18:41,149 - util.py[DEBUG]: search for mirror in candidates: '[]' | |
2021-08-24 20:18:41,149 - __init__.py[DEBUG]: filtered distro mirror info: {'primary': 'http://archive.ubuntu.com/ubuntu', 'security': 'http://security.ubuntu.com/ubuntu'} | |
2021-08-24 20:18:41,149 - cc_apt_configure.py[DEBUG]: Apt Mirror info: {'primary': 'http://archive.ubuntu.com/ubuntu', 'security': 'http://security.ubuntu.com/ubuntu', 'PRIMARY': 'http://archive.ubuntu.com/ubuntu', 'SECURITY': 'http://security.ubuntu.com/ubuntu', 'MIRROR': 'http://archive.ubuntu.com/ubuntu'} | |
2021-08-24 20:18:41,149 - cc_apt_configure.py[INFO]: No custom template provided, fall back to builtin | |
2021-08-24 20:18:41,149 - util.py[DEBUG]: Reading from /etc/cloud/templates/sources.list.ubuntu.tmpl (quiet=False) | |
2021-08-24 20:18:41,150 - util.py[DEBUG]: Read 2858 bytes from /etc/cloud/templates/sources.list.ubuntu.tmpl | |
2021-08-24 20:18:41,155 - util.py[DEBUG]: Writing to /etc/apt/sources.list - wb: [644] 3142 bytes | |
2021-08-24 20:18:41,156 - handlers.py[DEBUG]: finish: modules-config/config-apt-configure: SUCCESS: config-apt-configure ran successfully | |
2021-08-24 20:18:41,156 - stages.py[DEBUG]: Running module ubuntu-advantage (<module 'cloudinit.config.cc_ubuntu_advantage' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ubuntu_advantage.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,156 - handlers.py[DEBUG]: start: modules-config/config-ubuntu-advantage: running config-ubuntu-advantage with frequency once-per-instance | |
2021-08-24 20:18:41,156 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ubuntu_advantage - wb: [644] 25 bytes | |
2021-08-24 20:18:41,157 - helpers.py[DEBUG]: Running config-ubuntu-advantage using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ubuntu_advantage'>) | |
2021-08-24 20:18:41,157 - cc_ubuntu_advantage.py[DEBUG]: Skipping module named ubuntu-advantage, no 'ubuntu_advantage' configuration found | |
2021-08-24 20:18:41,157 - handlers.py[DEBUG]: finish: modules-config/config-ubuntu-advantage: SUCCESS: config-ubuntu-advantage ran successfully | |
2021-08-24 20:18:41,157 - stages.py[DEBUG]: Running module ntp (<module 'cloudinit.config.cc_ntp' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ntp.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,157 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance | |
2021-08-24 20:18:41,157 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ntp - wb: [644] 23 bytes | |
2021-08-24 20:18:41,157 - helpers.py[DEBUG]: Running config-ntp using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ntp'>) | |
2021-08-24 20:18:41,157 - cc_ntp.py[DEBUG]: Skipping module named ntp, not present or disabled by cfg | |
2021-08-24 20:18:41,157 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully | |
2021-08-24 20:18:41,157 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_timezone.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,158 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance | |
2021-08-24 20:18:41,158 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_timezone - wb: [644] 25 bytes | |
2021-08-24 20:18:41,158 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_timezone'>) | |
2021-08-24 20:18:41,158 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified | |
2021-08-24 20:18:41,158 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully | |
2021-08-24 20:18:41,158 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_disable_ec2_metadata.py'>) with frequency always | |
2021-08-24 20:18:41,158 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always | |
2021-08-24 20:18:41,158 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x7f978bff00a0>) | |
2021-08-24 20:18:41,158 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled | |
2021-08-24 20:18:41,158 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully | |
2021-08-24 20:18:41,158 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,158 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance | |
2021-08-24 20:18:41,159 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_runcmd - wb: [644] 24 bytes | |
2021-08-24 20:18:41,159 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_runcmd'>) | |
2021-08-24 20:18:41,159 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration | |
2021-08-24 20:18:41,159 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully | |
2021-08-24 20:18:41,159 - stages.py[DEBUG]: Running module byobu (<module 'cloudinit.config.cc_byobu' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_byobu.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,159 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance | |
2021-08-24 20:18:41,159 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_byobu - wb: [644] 24 bytes | |
2021-08-24 20:18:41,159 - helpers.py[DEBUG]: Running config-byobu using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_byobu'>) | |
2021-08-24 20:18:41,159 - cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found | |
2021-08-24 20:18:41,159 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu ran successfully | |
2021-08-24 20:18:41,160 - main.py[DEBUG]: Ran 14 modules with 1 failures | |
2021-08-24 20:18:41,160 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpnczcnf6y) - w: [644] 749 bytes/chars | |
2021-08-24 20:18:41,160 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2021-08-24 20:18:41,160 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2021-08-24 20:18:41,160 - util.py[DEBUG]: cloud-init mode 'modules' took 1.420 seconds (1.42) | |
2021-08-24 20:18:41,160 - handlers.py[DEBUG]: finish: modules-config: FAIL: running modules for config | |
2021-08-24 20:18:41,402 - util.py[DEBUG]: Cloud-init v. 21.3-1-g6803368d-0ubuntu1 running 'modules:final' at Tue, 24 Aug 2021 20:18:41 +0000. Up 19.78 seconds. | |
2021-08-24 20:18:41,423 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'> | |
2021-08-24 20:18:41,424 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,424 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance | |
2021-08-24 20:18:41,424 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_package_update_upgrade_install - wb: [644] 25 bytes | |
2021-08-24 20:18:41,424 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_package_update_upgrade_install'>) | |
2021-08-24 20:18:41,424 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully | |
2021-08-24 20:18:41,424 - stages.py[DEBUG]: Running module fan (<module 'cloudinit.config.cc_fan' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_fan.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,425 - handlers.py[DEBUG]: start: modules-final/config-fan: running config-fan with frequency once-per-instance | |
2021-08-24 20:18:41,425 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_fan - wb: [644] 25 bytes | |
2021-08-24 20:18:41,425 - helpers.py[DEBUG]: Running config-fan using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_fan'>) | |
2021-08-24 20:18:41,425 - cc_fan.py[DEBUG]: fan: no 'fan' config entry. disabling | |
2021-08-24 20:18:41,425 - handlers.py[DEBUG]: finish: modules-final/config-fan: SUCCESS: config-fan ran successfully | |
2021-08-24 20:18:41,425 - stages.py[DEBUG]: Running module landscape (<module 'cloudinit.config.cc_landscape' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_landscape.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,425 - handlers.py[DEBUG]: start: modules-final/config-landscape: running config-landscape with frequency once-per-instance | |
2021-08-24 20:18:41,425 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_landscape - wb: [644] 25 bytes | |
2021-08-24 20:18:41,426 - helpers.py[DEBUG]: Running config-landscape using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_landscape'>) | |
2021-08-24 20:18:41,426 - handlers.py[DEBUG]: finish: modules-final/config-landscape: SUCCESS: config-landscape ran successfully | |
2021-08-24 20:18:41,426 - stages.py[DEBUG]: Running module lxd (<module 'cloudinit.config.cc_lxd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_lxd.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,426 - handlers.py[DEBUG]: start: modules-final/config-lxd: running config-lxd with frequency once-per-instance | |
2021-08-24 20:18:41,426 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_lxd - wb: [644] 25 bytes | |
2021-08-24 20:18:41,426 - helpers.py[DEBUG]: Running config-lxd using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_lxd'>) | |
2021-08-24 20:18:41,426 - cc_lxd.py[DEBUG]: Skipping module named lxd, not present or disabled by cfg | |
2021-08-24 20:18:41,426 - handlers.py[DEBUG]: finish: modules-final/config-lxd: SUCCESS: config-lxd ran successfully | |
2021-08-24 20:18:41,426 - stages.py[DEBUG]: Running module ubuntu-drivers (<module 'cloudinit.config.cc_ubuntu_drivers' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ubuntu_drivers.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,426 - handlers.py[DEBUG]: start: modules-final/config-ubuntu-drivers: running config-ubuntu-drivers with frequency once-per-instance | |
2021-08-24 20:18:41,427 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ubuntu_drivers - wb: [644] 25 bytes | |
2021-08-24 20:18:41,427 - helpers.py[DEBUG]: Running config-ubuntu-drivers using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ubuntu_drivers'>) | |
2021-08-24 20:18:41,427 - cc_ubuntu_drivers.py[DEBUG]: Skipping module named ubuntu-drivers, no 'drivers' key in config | |
2021-08-24 20:18:41,427 - handlers.py[DEBUG]: finish: modules-final/config-ubuntu-drivers: SUCCESS: config-ubuntu-drivers ran successfully | |
2021-08-24 20:18:41,427 - stages.py[DEBUG]: Running module puppet (<module 'cloudinit.config.cc_puppet' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_puppet.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,427 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance | |
2021-08-24 20:18:41,427 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_puppet - wb: [644] 25 bytes | |
2021-08-24 20:18:41,427 - helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_puppet'>) | |
2021-08-24 20:18:41,427 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found | |
2021-08-24 20:18:41,428 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet ran successfully | |
2021-08-24 20:18:41,428 - stages.py[DEBUG]: Running module chef (<module 'cloudinit.config.cc_chef' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_chef.py'>) with frequency always | |
2021-08-24 20:18:41,428 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency always | |
2021-08-24 20:18:41,428 - helpers.py[DEBUG]: Running config-chef using lock (<cloudinit.helpers.DummyLock object at 0x7fadec1508b0>) | |
2021-08-24 20:18:41,428 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration | |
2021-08-24 20:18:41,428 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully | |
2021-08-24 20:18:41,428 - stages.py[DEBUG]: Running module mcollective (<module 'cloudinit.config.cc_mcollective' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mcollective.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,428 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance | |
2021-08-24 20:18:41,428 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_mcollective - wb: [644] 25 bytes | |
2021-08-24 20:18:41,428 - helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_mcollective'>) | |
2021-08-24 20:18:41,429 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration | |
2021-08-24 20:18:41,429 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective ran successfully | |
2021-08-24 20:18:41,429 - stages.py[DEBUG]: Running module salt-minion (<module 'cloudinit.config.cc_salt_minion' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_salt_minion.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,429 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance | |
2021-08-24 20:18:41,429 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_salt_minion - wb: [644] 25 bytes | |
2021-08-24 20:18:41,429 - helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_salt_minion'>) | |
2021-08-24 20:18:41,429 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration | |
2021-08-24 20:18:41,429 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion ran successfully | |
2021-08-24 20:18:41,429 - stages.py[DEBUG]: Running module reset_rmc (<module 'cloudinit.config.cc_reset_rmc' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_reset_rmc.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,429 - handlers.py[DEBUG]: start: modules-final/config-reset_rmc: running config-reset_rmc with frequency once-per-instance | |
2021-08-24 20:18:41,429 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_reset_rmc - wb: [644] 25 bytes | |
2021-08-24 20:18:41,430 - helpers.py[DEBUG]: Running config-reset_rmc using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_reset_rmc'>) | |
2021-08-24 20:18:41,430 - cc_reset_rmc.py[DEBUG]: module disabled, RSCT_PATH not present | |
2021-08-24 20:18:41,430 - handlers.py[DEBUG]: finish: modules-final/config-reset_rmc: SUCCESS: config-reset_rmc ran successfully | |
2021-08-24 20:18:41,430 - stages.py[DEBUG]: Running module refresh_rmc_and_interface (<module 'cloudinit.config.cc_refresh_rmc_and_interface' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_refresh_rmc_and_interface.py'>) with frequency always | |
2021-08-24 20:18:41,430 - handlers.py[DEBUG]: start: modules-final/config-refresh_rmc_and_interface: running config-refresh_rmc_and_interface with frequency always | |
2021-08-24 20:18:41,430 - helpers.py[DEBUG]: Running config-refresh_rmc_and_interface using lock (<cloudinit.helpers.DummyLock object at 0x7fadec1508b0>) | |
2021-08-24 20:18:41,430 - cc_refresh_rmc_and_interface.py[DEBUG]: No 'rmcctrl' in path, disabled | |
2021-08-24 20:18:41,430 - handlers.py[DEBUG]: finish: modules-final/config-refresh_rmc_and_interface: SUCCESS: config-refresh_rmc_and_interface ran successfully | |
2021-08-24 20:18:41,430 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_rightscale_userdata.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,431 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance | |
2021-08-24 20:18:41,431 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_rightscale_userdata - wb: [644] 24 bytes | |
2021-08-24 20:18:41,431 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_rightscale_userdata'>) | |
2021-08-24 20:18:41,431 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata | |
2021-08-24 20:18:41,431 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully | |
2021-08-24 20:18:41,431 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,431 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance | |
2021-08-24 20:18:41,431 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_scripts_vendor - wb: [644] 25 bytes | |
2021-08-24 20:18:41,432 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_scripts_vendor'>) | |
2021-08-24 20:18:41,432 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully | |
2021-08-24 20:18:41,432 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once | |
2021-08-24 20:18:41,432 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once | |
2021-08-24 20:18:41,432 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 25 bytes | |
2021-08-24 20:18:41,432 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>) | |
2021-08-24 20:18:41,432 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully | |
2021-08-24 20:18:41,432 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always | |
2021-08-24 20:18:41,432 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always | |
2021-08-24 20:18:41,433 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7fadec150d60>) | |
2021-08-24 20:18:41,433 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully | |
2021-08-24 20:18:41,433 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,433 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance | |
2021-08-24 20:18:41,433 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_scripts_per_instance - wb: [644] 25 bytes | |
2021-08-24 20:18:41,433 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_scripts_per_instance'>) | |
2021-08-24 20:18:41,433 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully | |
2021-08-24 20:18:41,433 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,433 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance | |
2021-08-24 20:18:41,433 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_scripts_user - wb: [644] 25 bytes | |
2021-08-24 20:18:41,434 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_scripts_user'>) | |
2021-08-24 20:18:41,434 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully | |
2021-08-24 20:18:41,434 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,434 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance | |
2021-08-24 20:18:41,434 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_ssh_authkey_fingerprints - wb: [644] 25 bytes | |
2021-08-24 20:18:41,434 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_ssh_authkey_fingerprints'>) | |
2021-08-24 20:18:41,435 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) | |
2021-08-24 20:18:41,435 - util.py[DEBUG]: Read 3287 bytes from /etc/ssh/sshd_config | |
2021-08-24 20:18:41,435 - util.py[DEBUG]: Reading from /home/mandy/.ssh/authorized_keys (quiet=False) | |
2021-08-24 20:18:41,435 - util.py[DEBUG]: Read 742 bytes from /home/mandy/.ssh/authorized_keys | |
2021-08-24 20:18:41,435 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: FAIL: running config-ssh-authkey-fingerprints with frequency once-per-instance | |
2021-08-24 20:18:41,435 - util.py[WARNING]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) failed | |
2021-08-24 20:18:41,435 - util.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 371, in multi_log | |
wfh.flush() | |
OSError: [Errno 5] Input/output error | |
During handling of the above exception, another exception occurred: | |
OSError: [Errno 5] Input/output error | |
During handling of the above exception, another exception occurred: | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/stages.py", line 965, in _run_modules | |
ran, _r = cc.run(run_name, mod.handle, func_args, | |
File "/usr/lib/python3/dist-packages/cloudinit/cloud.py", line 54, in run | |
return self._runners.run(name, functor, args, freq, clear_on_fail) | |
File "/usr/lib/python3/dist-packages/cloudinit/helpers.py", line 186, in run | |
results = functor(*args) | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py", line 108, in handle | |
_pprint_key_entries(user_name, key_fn, | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py", line 94, in _pprint_key_entries | |
util.multi_log(text="%s%s\n" % (prefix, line), | |
File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 371, in multi_log | |
wfh.flush() | |
OSError: [Errno 5] Input/output error | |
2021-08-24 20:18:41,438 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,439 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance | |
2021-08-24 20:18:41,439 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_keys_to_console - wb: [644] 25 bytes | |
2021-08-24 20:18:41,439 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_keys_to_console'>) | |
2021-08-24 20:18:41,439 - subp.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True) | |
2021-08-24 20:18:41,469 - cc_keys_to_console.py[WARNING]: Writing keys to the system console failed! | |
2021-08-24 20:18:41,469 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: FAIL: running config-keys-to-console with frequency once-per-instance | |
2021-08-24 20:18:41,469 - util.py[WARNING]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) failed | |
2021-08-24 20:18:41,469 - util.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) failed | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 371, in multi_log | |
wfh.flush() | |
OSError: [Errno 5] Input/output error | |
During handling of the above exception, another exception occurred: | |
OSError: [Errno 5] Input/output error | |
During handling of the above exception, another exception occurred: | |
Traceback (most recent call last): | |
File "/usr/lib/python3/dist-packages/cloudinit/stages.py", line 965, in _run_modules | |
ran, _r = cc.run(run_name, mod.handle, func_args, | |
File "/usr/lib/python3/dist-packages/cloudinit/cloud.py", line 54, in run | |
return self._runners.run(name, functor, args, freq, clear_on_fail) | |
File "/usr/lib/python3/dist-packages/cloudinit/helpers.py", line 186, in run | |
results = functor(*args) | |
File "/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py", line 80, in handle | |
util.multi_log("%s\n" % (stdout.strip()), | |
File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 371, in multi_log | |
wfh.flush() | |
OSError: [Errno 5] Input/output error | |
2021-08-24 20:18:41,470 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_phone_home.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,470 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance | |
2021-08-24 20:18:41,470 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_phone_home - wb: [644] 25 bytes | |
2021-08-24 20:18:41,471 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_phone_home'>) | |
2021-08-24 20:18:41,471 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found | |
2021-08-24 20:18:41,471 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully | |
2021-08-24 20:18:41,471 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_final_message.py'>) with frequency always | |
2021-08-24 20:18:41,471 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always | |
2021-08-24 20:18:41,471 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7fadec0e7550>) | |
2021-08-24 20:18:41,471 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2021-08-24 20:18:41,471 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2021-08-24 20:18:41,473 - util.py[DEBUG]: Cloud-init v. 21.3-1-g6803368d-0ubuntu1 finished at Tue, 24 Aug 2021 20:18:41 +0000. Datasource DataSourceVMware. Up 19.88 seconds | |
2021-08-24 20:18:41,473 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 71 bytes | |
2021-08-24 20:18:41,473 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully | |
2021-08-24 20:18:41,473 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_power_state_change.py'>) with frequency once-per-instance | |
2021-08-24 20:18:41,473 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance | |
2021-08-24 20:18:41,473 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/cloud/sem/config_power_state_change - wb: [644] 25 bytes | |
2021-08-24 20:18:41,474 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/cloud/sem/config_power_state_change'>) | |
2021-08-24 20:18:41,474 - cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing | |
2021-08-24 20:18:41,474 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully | |
2021-08-24 20:18:41,474 - main.py[DEBUG]: Ran 22 modules with 2 failures | |
2021-08-24 20:18:41,474 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpeuwavw45) - w: [644] 911 bytes/chars | |
2021-08-24 20:18:41,474 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmp1a77f4hs) - w: [644] 402 bytes/chars | |
2021-08-24 20:18:41,474 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' | |
2021-08-24 20:18:41,475 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) | |
2021-08-24 20:18:41,475 - util.py[DEBUG]: Read 12 bytes from /proc/uptime | |
2021-08-24 20:18:41,475 - util.py[DEBUG]: cloud-init mode 'modules' took 0.110 seconds (0.11) | |
2021-08-24 20:18:41,475 - handlers.py[DEBUG]: finish: modules-final: FAIL: running modules for final |
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
instance-id: cloud | |
local-hostname: cloud | |
network: | |
version: 2 | |
ethernets: | |
nics: | |
match: | |
name: ens* | |
dhcp4: yes |
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
#cloud-config | |
users: | |
- default | |
- name: mandy | |
primary_group: mandy | |
sudo: ALL=(ALL) NOPASSWD:ALL | |
groups: sudo, wheel | |
ssh_import_id: None | |
lock_passwd: true | |
ssh_authorized_keys: | |
- ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDE0c5FczvcGSh/tG4iw+Fhfi/O5/EvUM/96js65tly4++YTXK1d9jcznPS5ruDlbIZ30oveCBd3kT8LLVFwzh6hepYTf0YmCTpF4eDunyqmpCXDvVscQYRXyasEm5olGmVe05RrCJSeSShAeptv4ueIn40kZKOghinGWLDSZG4+FFfgrmcMCpx5YSCtX2gvnEYZJr0czt4rxOZuuP7PkJKgC/mt2PcPjooeX00vAj81jjU2f3XKrjjz2u2+KIt9eba+vOQ6HiC8c2IzRkUAJ5i1atLy8RIbejo23+0P4N2jjk17QySFOVHwPBDTYb0/0M/4ideeU74EN/CgVsvO6JrLsPBR4dojkV5qNbMNxIVv5cUwIy2ThlLgqpNCeFIDLCWNZEFKlEuNeSQ2mPtIO7ETxEL2Cz5y/7AIuildzYMc6wi2bofRC8HmQ7rMXRWdwLKWsR0L7SKjHblIwarxOGqLnUI+k2E71YoP7SZSlxaKi17pqkr0OMCF+kKqvcvHAQuwGqyumTEWOlH6TCx1dSPrW+pVCZSHSJtSTfDW2uzL6y8k10MT06+pVunSrWo5LHAXcS91htHV1M1UrH/tZKSpjYtjMb5+RonfhaFRNzvj7cCE1f3Kp8UVqAdcGBTtReoE8eRUT63qIxjw03a7VwAyB2w+9cu1R9/vAo8SBeRqw== sakutz@gmail.com |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment