2022-03-02 01:33:48,327 - util.py[DEBUG]: Cloud-init v. 21.1-7.0.2.el8_5.3 running 'init-local' at Wed, 02 Mar 2022 01:33:48 +0000. Up 24.03 seconds. 2022-03-02 01:33:48,327 - main.py[DEBUG]: No kernel command line url found. 2022-03-02 01:33:48,327 - main.py[DEBUG]: Closing stdin. 2022-03-02 01:33:48,327 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False) 2022-03-02 01:33:48,328 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False) 2022-03-02 01:33:48,328 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False) 2022-03-02 01:33:48,328 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=True) 2022-03-02 01:33:48,329 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False) 2022-03-02 01:33:48,329 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False) 2022-03-02 01:33:48,330 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=True) 2022-03-02 01:33:48,330 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False) 2022-03-02 01:33:48,331 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=True) 2022-03-02 01:33:48,332 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False) 2022-03-02 01:33:48,332 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=True) 2022-03-02 01:33:48,334 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False) 2022-03-02 01:33:48,334 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=True) 2022-03-02 01:33:48,335 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False) 2022-03-02 01:33:48,335 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=True) 2022-03-02 01:33:48,337 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False) 2022-03-02 01:33:48,337 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False) 2022-03-02 01:33:48,337 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes 2022-03-02 01:33:48,338 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False) 2022-03-02 01:33:48,338 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False) 2022-03-02 01:33:48,338 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished 2022-03-02 01:33:48,338 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net 2022-03-02 01:33:48,338 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check] 2022-03-02 01:33:48,338 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2022-03-02 01:33:48,339 - stages.py[DEBUG]: no cache found 2022-03-02 01:33:48,339 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found 2022-03-02 01:33:48,339 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2022-03-02 01:33:48,342 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:48,342 - __init__.py[DEBUG]: Looking for data source in: ['Oracle', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM'] 2022-03-02 01:33:48,343 - __init__.py[DEBUG]: Searching for local data source in: ['DataSourceOracle'] 2022-03-02 01:33:48,343 - handlers.py[DEBUG]: start: init-local/search-Oracle: searching for local data from DataSourceOracle 2022-03-02 01:33:48,343 - __init__.py[DEBUG]: Seeing if we can get any data from 2022-03-02 01:33:48,343 - __init__.py[DEBUG]: Update datasource metadata and network config due to events: New instance first boot 2022-03-02 01:33:48,343 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/chassis_asset_tag 2022-03-02 01:33:48,343 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_uuid 2022-03-02 01:33:48,344 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,344 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,344 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,344 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,344 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,344 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,344 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,344 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,344 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,344 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,344 - url_helper.py[DEBUG]: [0/3] open 'http://169.254.169.254/opc/v2/instance/' with {'url': 'http://169.254.169.254/opc/v2/instance/', 'allow_redirects': True, 'method': 'GET', 'headers': {'User-Agent': 'Cloud-Init/21.1-7.0.2.el8_5.3', 'Authorization': 'Bearer Oracle'}} configuration 2022-03-02 01:33:48,348 - url_helper.py[DEBUG]: Read from http://169.254.169.254/opc/v2/instance/ (200, 2472b) after 1 attempts 2022-03-02 01:33:48,350 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmprfadra61) - w: [600] 7705 bytes/chars 2022-03-02 01:33:48,350 - atomic_helper.py[DEBUG]: Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpbee2aor1) - w: [644] 4426 bytes/chars 2022-03-02 01:33:48,350 - handlers.py[DEBUG]: finish: init-local/search-Oracle: SUCCESS: found local data from DataSourceOracle 2022-03-02 01:33:48,350 - stages.py[INFO]: Loaded datasource DataSourceOracle - DataSourceOracle 2022-03-02 01:33:48,350 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2022-03-02 01:33:48,350 - util.py[DEBUG]: Read 1257 bytes from /etc/cloud/cloud.cfg 2022-03-02 01:33:48,350 - util.py[DEBUG]: Attempting to load yaml from string of length 1257 with allowed root types (,) 2022-03-02 01:33:48,355 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_oci.cfg (quiet=False) 2022-03-02 01:33:48,355 - util.py[DEBUG]: Read 1661 bytes from /etc/cloud/cloud.cfg.d/99_oci.cfg 2022-03-02 01:33:48,355 - util.py[DEBUG]: Attempting to load yaml from string of length 1661 with allowed root types (,) 2022-03-02 01:33:48,359 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2022-03-02 01:33:48,359 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2022-03-02 01:33:48,359 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2022-03-02 01:33:48,361 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2022-03-02 01:33:48,361 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg 2022-03-02 01:33:48,361 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (,) 2022-03-02 01:33:48,362 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:48,362 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:48,362 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2022-03-02 01:33:48,362 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq' 2022-03-02 01:33:48,362 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq (recursive=True) 2022-03-02 01:33:48,363 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/handlers (recursive=False) 2022-03-02 01:33:48,363 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq (recursive=True) 2022-03-02 01:33:48,364 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/scripts (recursive=False) 2022-03-02 01:33:48,364 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq (recursive=True) 2022-03-02 01:33:48,365 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem (recursive=False) 2022-03-02 01:33:48,365 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (quiet=False) 2022-03-02 01:33:48,365 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource - wb: [644] 35 bytes 2022-03-02 01:33:48,365 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (recursive=False) 2022-03-02 01:33:48,366 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (recursive=False) 2022-03-02 01:33:48,366 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 35 bytes 2022-03-02 01:33:48,366 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-datasource (recursive=False) 2022-03-02 01:33:48,367 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-datasource (recursive=False) 2022-03-02 01:33:48,367 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2022-03-02 01:33:48,367 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID 2022-03-02 01:33:48,367 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 91 bytes 2022-03-02 01:33:48,367 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/instance-id (recursive=False) 2022-03-02 01:33:48,368 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/instance-id (recursive=False) 2022-03-02 01:33:48,368 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 91 bytes 2022-03-02 01:33:48,368 - util.py[DEBUG]: Restoring selinux mode for /run/cloud-init/.instance-id (recursive=False) 2022-03-02 01:33:48,368 - util.py[DEBUG]: Restoring selinux mode for /run/cloud-init/.instance-id (recursive=False) 2022-03-02 01:33:48,368 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 24 bytes 2022-03-02 01:33:48,368 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-instance-id (recursive=False) 2022-03-02 01:33:48,369 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-instance-id (recursive=False) 2022-03-02 01:33:48,369 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 7336 bytes 2022-03-02 01:33:48,369 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:48,370 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:48,370 - main.py[DEBUG]: [local] init will now be targeting instance id: ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq. new=True 2022-03-02 01:33:48,370 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2022-03-02 01:33:48,370 - util.py[DEBUG]: Read 1257 bytes from /etc/cloud/cloud.cfg 2022-03-02 01:33:48,370 - util.py[DEBUG]: Attempting to load yaml from string of length 1257 with allowed root types (,) 2022-03-02 01:33:48,374 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_oci.cfg (quiet=False) 2022-03-02 01:33:48,375 - util.py[DEBUG]: Read 1661 bytes from /etc/cloud/cloud.cfg.d/99_oci.cfg 2022-03-02 01:33:48,375 - util.py[DEBUG]: Attempting to load yaml from string of length 1661 with allowed root types (,) 2022-03-02 01:33:48,378 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2022-03-02 01:33:48,379 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2022-03-02 01:33:48,379 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2022-03-02 01:33:48,381 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2022-03-02 01:33:48,381 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg 2022-03-02 01:33:48,381 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (,) 2022-03-02 01:33:48,381 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:48,381 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:48,382 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:48,382 - cc_set_hostname.py[DEBUG]: Setting the hostname to instance-20220302-1029 (instance-20220302-1029) 2022-03-02 01:33:48,382 - subp.py[DEBUG]: Running command ['hostnamectl', 'set-hostname', 'instance-20220302-1029'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:48,430 - __init__.py[DEBUG]: Non-persistently setting the system hostname to instance-20220302-1029 2022-03-02 01:33:48,430 - subp.py[DEBUG]: Running command ['hostname', 'instance-20220302-1029'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:48,433 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/set-hostname (via temporary file /var/lib/cloud/data/tmpoulz0h7d) - w: [644] 77 bytes/chars 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,434 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,434 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/name_assign_type (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/name_assign_type 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/name_assign_type (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/name_assign_type 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/name_assign_type (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/name_assign_type 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/name_assign_type (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/name_assign_type 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/carrier (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/carrier 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/dormant (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/dormant 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/operstate (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/ens800f1/operstate 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/carrier (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/carrier 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/carrier (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/carrier 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/carrier (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/carrier 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,435 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,435 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/addr_assign_type 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/uevent (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f1/uevent 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,436 - __init__.py[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/device/device (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f1/device/device 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/addr_assign_type 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/uevent (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f0/uevent 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/addr_assign_type 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/uevent (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f0/uevent 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/device/device (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f0/device/device 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2022-03-02 01:33:48,436 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2022-03-02 01:33:48,436 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/addr_assign_type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/uevent (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f1/uevent 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/device/device (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f1/device/device 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/addr_assign_type 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/uevent (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f1/uevent 2022-03-02 01:33:48,437 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,437 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/device/device (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f1/device/device 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/addr_assign_type 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/uevent (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f0/uevent 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/addr_assign_type 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/uevent (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f0/uevent 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/device/device (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f0/device/device 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/addr_assign_type 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/uevent (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f1/uevent 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,438 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,438 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/device/device (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f1/device/device 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/type 2022-03-02 01:33:48,439 - networking.py[DEBUG]: net: all expected physical devices present 2022-03-02 01:33:48,439 - stages.py[DEBUG]: applying net config names for {'ethernets': {'ens300f0': {'dhcp4': True, 'set-name': 'ens300f0', 'match': {'macaddress': 'b8:ce:f6:cb:65:ac'}}}, 'version': 2} 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/addr_assign_type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/uevent (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f1/uevent 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/device/device (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f1/device/device 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/addr_assign_type 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/uevent (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f0/uevent 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:48,439 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/addr_assign_type (quiet=False) 2022-03-02 01:33:48,439 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/addr_assign_type 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/uevent (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f0/uevent 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/device/device (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f0/device/device 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/addr_assign_type (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/addr_assign_type 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/uevent (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f1/uevent 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/device/device (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f1/device/device 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/operstate (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/ens800f1/operstate 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/operstate (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens300f0/operstate 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/operstate (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens800f0/operstate 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate 2022-03-02 01:33:48,440 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/operstate (quiet=False) 2022-03-02 01:33:48,440 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens300f1/operstate 2022-03-02 01:33:48,441 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:48,443 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:48,446 - __init__.py[DEBUG]: no work necessary for renaming of [['b8:ce:f6:cb:65:ac', 'ens300f0', 'mlx5_core', '0x101d']] 2022-03-02 01:33:48,446 - stages.py[INFO]: Applying network configuration from ds bringup=False: {'ethernets': {'ens300f0': {'dhcp4': True, 'set-name': 'ens300f0', 'match': {'macaddress': 'b8:ce:f6:cb:65:ac'}}}, 'version': 2} 2022-03-02 01:33:48,446 - __init__.py[DEBUG]: Selected renderer 'sysconfig' from priority list: None 2022-03-02 01:33:48,447 - dmi.py[DEBUG]: querying dmi data /sys/class/dmi/id/chassis_asset_tag 2022-03-02 01:33:48,447 - network_state.py[DEBUG]: v2(ethernets) -> v1(physical): {'type': 'physical', 'name': 'ens300f0', 'mac_address': 'b8:ce:f6:cb:65:ac', 'match': {'macaddress': 'b8:ce:f6:cb:65:ac'}, 'subnets': [{'type': 'dhcp4'}]} 2022-03-02 01:33:48,448 - network_state.py[DEBUG]: v2_common: handling config: {'ens300f0': {'dhcp4': True, 'set-name': 'ens300f0', 'match': {'macaddress': 'b8:ce:f6:cb:65:ac'}}} 2022-03-02 01:33:48,448 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-ens300f0 - wb: [644] 163 bytes 2022-03-02 01:33:48,450 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-ens300f0 (recursive=False) 2022-03-02 01:33:48,450 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-ens300f0 (recursive=False) 2022-03-02 01:33:48,451 - sysconfig.py[DEBUG]: Enabled ifcfg-rh NetworkManager plugins 2022-03-02 01:33:48,451 - util.py[DEBUG]: Reading from /etc/sysconfig/network (quiet=True) 2022-03-02 01:33:48,451 - util.py[DEBUG]: Read 22 bytes from /etc/sysconfig/network 2022-03-02 01:33:48,451 - util.py[DEBUG]: Writing to /etc/sysconfig/network - wb: [644] 109 bytes 2022-03-02 01:33:48,452 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False) 2022-03-02 01:33:48,453 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False) 2022-03-02 01:33:48,453 - main.py[DEBUG]: [local] Exiting. datasource DataSourceOracle not in local mode. 2022-03-02 01:33:48,453 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpcsqc0y50) - w: [644] 489 bytes/chars 2022-03-02 01:33:48,453 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2022-03-02 01:33:48,453 - util.py[DEBUG]: Read 14 bytes from /proc/uptime 2022-03-02 01:33:48,453 - util.py[DEBUG]: cloud-init mode 'init' took 0.169 seconds (0.16) 2022-03-02 01:33:48,454 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources 2022-03-02 01:33:53,251 - util.py[DEBUG]: Cloud-init v. 21.1-7.0.2.el8_5.3 running 'init' at Wed, 02 Mar 2022 01:33:53 +0000. Up 30.51 seconds. 2022-03-02 01:33:53,251 - main.py[DEBUG]: No kernel command line url found. 2022-03-02 01:33:53,252 - main.py[DEBUG]: Closing stdin. 2022-03-02 01:33:53,252 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False) 2022-03-02 01:33:53,252 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False) 2022-03-02 01:33:53,252 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False) 2022-03-02 01:33:53,253 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False) 2022-03-02 01:33:53,253 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False) 2022-03-02 01:33:53,253 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False) 2022-03-02 01:33:53,253 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False) 2022-03-02 01:33:53,254 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False) 2022-03-02 01:33:53,254 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False) 2022-03-02 01:33:53,254 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False) 2022-03-02 01:33:53,254 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False) 2022-03-02 01:33:53,255 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [644] 0 bytes 2022-03-02 01:33:53,255 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False) 2022-03-02 01:33:53,256 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False) 2022-03-02 01:33:53,256 - subp.py[DEBUG]: Running command ['ip', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:53,259 - subp.py[DEBUG]: Running command ['ip', '-o', 'route', 'list'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:53,261 - subp.py[DEBUG]: Running command ['ip', '--oneline', '-6', 'route', 'list', 'table', 'all'] with allowed return codes [0, 1] (shell=False, capture=True) 2022-03-02 01:33:53,264 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early. 2022-03-02 01:33:53,264 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early. 2022-03-02 01:33:53,264 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust] 2022-03-02 01:33:53,264 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2022-03-02 01:33:53,264 - util.py[DEBUG]: Read 7336 bytes from /var/lib/cloud/instance/obj.pkl 2022-03-02 01:33:53,265 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False) 2022-03-02 01:33:53,265 - util.py[DEBUG]: Read 91 bytes from /run/cloud-init/.instance-id 2022-03-02 01:33:53,265 - stages.py[DEBUG]: restored from cache with run check: DataSourceOracle 2022-03-02 01:33:53,265 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceOracle 2022-03-02 01:33:53,265 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2022-03-02 01:33:53,265 - util.py[DEBUG]: Read 1257 bytes from /etc/cloud/cloud.cfg 2022-03-02 01:33:53,265 - util.py[DEBUG]: Attempting to load yaml from string of length 1257 with allowed root types (,) 2022-03-02 01:33:53,270 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_oci.cfg (quiet=False) 2022-03-02 01:33:53,270 - util.py[DEBUG]: Read 1661 bytes from /etc/cloud/cloud.cfg.d/99_oci.cfg 2022-03-02 01:33:53,270 - util.py[DEBUG]: Attempting to load yaml from string of length 1661 with allowed root types (,) 2022-03-02 01:33:53,274 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2022-03-02 01:33:53,274 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2022-03-02 01:33:53,274 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2022-03-02 01:33:53,276 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2022-03-02 01:33:53,276 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg 2022-03-02 01:33:53,276 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (,) 2022-03-02 01:33:53,276 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:53,276 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:53,277 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance 2022-03-02 01:33:53,277 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq' 2022-03-02 01:33:53,277 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/handlers (recursive=False) 2022-03-02 01:33:53,277 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/scripts (recursive=False) 2022-03-02 01:33:53,278 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem (recursive=False) 2022-03-02 01:33:53,278 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (quiet=False) 2022-03-02 01:33:53,278 - util.py[DEBUG]: Read 35 bytes from /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource 2022-03-02 01:33:53,278 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource - wb: [644] 35 bytes 2022-03-02 01:33:53,280 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (recursive=False) 2022-03-02 01:33:53,281 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/datasource (recursive=False) 2022-03-02 01:33:53,281 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [644] 35 bytes 2022-03-02 01:33:53,282 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-datasource (recursive=False) 2022-03-02 01:33:53,283 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-datasource (recursive=False) 2022-03-02 01:33:53,283 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False) 2022-03-02 01:33:53,283 - util.py[DEBUG]: Read 91 bytes from /var/lib/cloud/data/instance-id 2022-03-02 01:33:53,283 - stages.py[DEBUG]: previous iid found to be ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq 2022-03-02 01:33:53,283 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [644] 91 bytes 2022-03-02 01:33:53,284 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/instance-id (recursive=False) 2022-03-02 01:33:53,285 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/instance-id (recursive=False) 2022-03-02 01:33:53,285 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [644] 91 bytes 2022-03-02 01:33:53,285 - util.py[DEBUG]: Restoring selinux mode for /run/cloud-init/.instance-id (recursive=False) 2022-03-02 01:33:53,285 - util.py[DEBUG]: Restoring selinux mode for /run/cloud-init/.instance-id (recursive=False) 2022-03-02 01:33:53,285 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [644] 91 bytes 2022-03-02 01:33:53,287 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-instance-id (recursive=False) 2022-03-02 01:33:53,287 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data/previous-instance-id (recursive=False) 2022-03-02 01:33:53,287 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 7349 bytes 2022-03-02 01:33:53,289 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:53,289 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:53,289 - main.py[DEBUG]: [net] init will now be targeting instance id: ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq. new=False 2022-03-02 01:33:53,289 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2022-03-02 01:33:53,290 - util.py[DEBUG]: Read 1257 bytes from /etc/cloud/cloud.cfg 2022-03-02 01:33:53,290 - util.py[DEBUG]: Attempting to load yaml from string of length 1257 with allowed root types (,) 2022-03-02 01:33:53,294 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_oci.cfg (quiet=False) 2022-03-02 01:33:53,294 - util.py[DEBUG]: Read 1661 bytes from /etc/cloud/cloud.cfg.d/99_oci.cfg 2022-03-02 01:33:53,294 - util.py[DEBUG]: Attempting to load yaml from string of length 1661 with allowed root types (,) 2022-03-02 01:33:53,298 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2022-03-02 01:33:53,298 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2022-03-02 01:33:53,298 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2022-03-02 01:33:53,300 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2022-03-02 01:33:53,300 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg 2022-03-02 01:33:53,300 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (,) 2022-03-02 01:33:53,300 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:53,300 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:53,301 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:53,301 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:53,301 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,301 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,301 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:53,301 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:53,301 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:53,301 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:53,301 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:53,301 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/name_assign_type (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/name_assign_type 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/name_assign_type (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/name_assign_type 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/name_assign_type (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/name_assign_type 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/name_assign_type (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/name_assign_type 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/carrier (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/carrier 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/carrier (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/carrier 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/dormant (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/dormant 2022-03-02 01:33:53,302 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/operstate (quiet=False) 2022-03-02 01:33:53,302 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/ens800f1/operstate 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/carrier (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/carrier 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/carrier (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/carrier 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/addr_assign_type (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/addr_assign_type 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/uevent (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f1/uevent 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:53,303 - __init__.py[DEBUG]: ovs-vsctl not in PATH; not detecting Open vSwitch interfaces 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/device/device (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f1/device/device 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/addr_assign_type (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/addr_assign_type 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/uevent (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f0/uevent 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/addr_assign_type (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/addr_assign_type 2022-03-02 01:33:53,303 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/uevent (quiet=False) 2022-03-02 01:33:53,303 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f0/uevent 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/device/device (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f0/device/device 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/addr_assign_type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/addr_assign_type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/uevent (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f1/uevent 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/device/device (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f1/device/device 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/lo/type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 4 bytes from /sys/class/net/lo/type 2022-03-02 01:33:53,304 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/type (quiet=False) 2022-03-02 01:33:53,304 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/type 2022-03-02 01:33:53,304 - __init__.py[DEBUG]: Datasource DataSourceOracle not updated for events: System boot 2022-03-02 01:33:53,304 - stages.py[DEBUG]: No network config applied. Neither a new instance nor datasource network update on 'System boot' event 2022-03-02 01:33:53,304 - stages.py[DEBUG]: applying net config names for {'ethernets': {'ens300f0': {'dhcp4': True, 'set-name': 'ens300f0', 'match': {'macaddress': 'b8:ce:f6:cb:65:ac'}}}, 'version': 2} 2022-03-02 01:33:53,305 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/addr_assign_type (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f1/addr_assign_type 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/uevent (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f1/uevent 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/address (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f1/address 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/device/device (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f1/device/device 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/addr_assign_type (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f0/addr_assign_type 2022-03-02 01:33:53,305 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/uevent (quiet=False) 2022-03-02 01:33:53,305 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f0/uevent 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/address (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f0/address 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/device/device (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f0/device/device 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/addr_assign_type (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens800f0/addr_assign_type 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/uevent (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens800f0/uevent 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/address (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens800f0/address 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/device/device (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens800f0/device/device 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/addr_assign_type (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/ens300f1/addr_assign_type 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/uevent (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 29 bytes from /sys/class/net/ens300f1/uevent 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/address (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/ens300f1/address 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/device/device (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/ens300f1/device/device 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f1/operstate (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/ens800f1/operstate 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens300f0/operstate (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens300f0/operstate 2022-03-02 01:33:53,306 - util.py[DEBUG]: Reading from /sys/class/net/ens800f0/operstate (quiet=False) 2022-03-02 01:33:53,306 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens800f0/operstate 2022-03-02 01:33:53,307 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False) 2022-03-02 01:33:53,307 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate 2022-03-02 01:33:53,307 - util.py[DEBUG]: Reading from /sys/class/net/ens300f1/operstate (quiet=False) 2022-03-02 01:33:53,307 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/ens300f1/operstate 2022-03-02 01:33:53,307 - subp.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:53,308 - subp.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:53,310 - __init__.py[DEBUG]: no work necessary for renaming of [['b8:ce:f6:cb:65:ac', 'ens300f0', 'mlx5_core', '0x101d']] 2022-03-02 01:33:53,311 - handlers.py[DEBUG]: start: init-network/setup-datasource: setting up datasource 2022-03-02 01:33:53,311 - handlers.py[DEBUG]: finish: init-network/setup-datasource: SUCCESS: setting up datasource 2022-03-02 01:33:53,311 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt - wb: [600] 0 bytes 2022-03-02 01:33:53,311 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt (recursive=False) 2022-03-02 01:33:53,312 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt (recursive=False) 2022-03-02 01:33:53,314 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt.i - wb: [600] 308 bytes 2022-03-02 01:33:53,315 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt.i (recursive=False) 2022-03-02 01:33:53,315 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/user-data.txt.i (recursive=False) 2022-03-02 01:33:53,315 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt - wb: [600] 0 bytes 2022-03-02 01:33:53,316 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt (recursive=False) 2022-03-02 01:33:53,316 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt (recursive=False) 2022-03-02 01:33:53,317 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt.i - wb: [600] 308 bytes 2022-03-02 01:33:53,317 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt.i (recursive=False) 2022-03-02 01:33:53,318 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data.txt.i (recursive=False) 2022-03-02 01:33:53,318 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt - wb: [600] 0 bytes 2022-03-02 01:33:53,318 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt (recursive=False) 2022-03-02 01:33:53,319 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt (recursive=False) 2022-03-02 01:33:53,320 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt.i - wb: [600] 308 bytes 2022-03-02 01:33:53,320 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt.i (recursive=False) 2022-03-02 01:33:53,320 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/vendor-data2.txt.i (recursive=False) 2022-03-02 01:33:53,321 - util.py[DEBUG]: Reading from /var/lib/cloud/data/set-hostname (quiet=False) 2022-03-02 01:33:53,321 - util.py[DEBUG]: Read 77 bytes from /var/lib/cloud/data/set-hostname 2022-03-02 01:33:53,321 - cc_set_hostname.py[DEBUG]: No hostname changes. Skipping set-hostname 2022-03-02 01:33:53,321 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/consume_data - wb: [644] 25 bytes 2022-03-02 01:33:53,321 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/consume_data (recursive=False) 2022-03-02 01:33:53,322 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/consume_data (recursive=False) 2022-03-02 01:33:53,322 - helpers.py[DEBUG]: Running consume_data using lock () 2022-03-02 01:33:53,322 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data 2022-03-02 01:33:53,322 - launch_index.py[DEBUG]: Discarding 0 multipart messages which do not match launch index 0 2022-03-02 01:33:53,323 - stages.py[DEBUG]: Added default handler for {'text/cloud-config-jsonp', 'text/cloud-config'} from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] 2022-03-02 01:33:53,323 - stages.py[DEBUG]: Added default handler for {'text/x-shellscript'} from ShellScriptPartHandler: [['text/x-shellscript']] 2022-03-02 01:33:53,323 - stages.py[DEBUG]: Added default handler for {'text/cloud-boothook'} from BootHookPartHandler: [['text/cloud-boothook']] 2022-03-02 01:33:53,323 - stages.py[DEBUG]: Added default handler for {'text/upstart-job'} from UpstartJobPartHandler: [['text/upstart-job']] 2022-03-02 01:33:53,323 - stages.py[DEBUG]: Added default handler for {'text/jinja2'} from JinjaTemplatePartHandler: [['text/jinja2']] 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__begin__, None, 3) with frequency once-per-instance 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: {'MIME-Version': '1.0', 'Content-Type': 'text/x-not-multipart', 'Content-Disposition': 'attachment; filename="part-001"'} 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Empty payload of type text/x-not-multipart 2022-03-02 01:33:53,323 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance 2022-03-02 01:33:53,323 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/cloud-config.txt - wb: [600] 0 bytes 2022-03-02 01:33:53,323 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/cloud-config.txt (recursive=False) 2022-03-02 01:33:53,324 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/cloud-config.txt (recursive=False) 2022-03-02 01:33:53,324 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,324 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,324 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance 2022-03-02 01:33:53,324 - __init__.py[DEBUG]: Calling handler JinjaTemplatePartHandler: [['text/jinja2']] (__end__, None, 3) with frequency once-per-instance 2022-03-02 01:33:53,324 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data 2022-03-02 01:33:53,324 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data 2022-03-02 01:33:53,324 - stages.py[DEBUG]: no vendordata from datasource 2022-03-02 01:33:53,324 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data 2022-03-02 01:33:53,324 - handlers.py[DEBUG]: start: init-network/consume-vendor-data2: reading and applying vendor-data2 2022-03-02 01:33:53,324 - stages.py[DEBUG]: no vendordata2 from datasource 2022-03-02 01:33:53,324 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data2: SUCCESS: reading and applying vendor-data2 2022-03-02 01:33:53,325 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False) 2022-03-02 01:33:53,325 - util.py[DEBUG]: Read 1257 bytes from /etc/cloud/cloud.cfg 2022-03-02 01:33:53,325 - util.py[DEBUG]: Attempting to load yaml from string of length 1257 with allowed root types (,) 2022-03-02 01:33:53,329 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_oci.cfg (quiet=False) 2022-03-02 01:33:53,329 - util.py[DEBUG]: Read 1661 bytes from /etc/cloud/cloud.cfg.d/99_oci.cfg 2022-03-02 01:33:53,329 - util.py[DEBUG]: Attempting to load yaml from string of length 1661 with allowed root types (,) 2022-03-02 01:33:53,333 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2022-03-02 01:33:53,333 - util.py[DEBUG]: Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2022-03-02 01:33:53,334 - util.py[DEBUG]: Attempting to load yaml from string of length 2070 with allowed root types (,) 2022-03-02 01:33:53,336 - util.py[DEBUG]: Reading from /run/cloud-init/cloud.cfg (quiet=False) 2022-03-02 01:33:53,336 - util.py[DEBUG]: Read 34 bytes from /run/cloud-init/cloud.cfg 2022-03-02 01:33:53,336 - util.py[DEBUG]: Attempting to load yaml from string of length 34 with allowed root types (,) 2022-03-02 01:33:53,336 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:53,336 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:53,336 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2022-03-02 01:33:53,336 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2022-03-02 01:33:53,336 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:53,336 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:53,337 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2022-03-02 01:33:53,337 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2022-03-02 01:33:53,337 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (,) 2022-03-02 01:33:53,337 - util.py[DEBUG]: loaded blob returned None, returning default. 2022-03-02 01:33:53,338 - handlers.py[DEBUG]: start: init-network/activate-datasource: activating datasource 2022-03-02 01:33:53,338 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [400] 10295 bytes 2022-03-02 01:33:53,338 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:53,339 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/obj.pkl (recursive=False) 2022-03-02 01:33:53,339 - handlers.py[DEBUG]: finish: init-network/activate-datasource: SUCCESS: activating datasource 2022-03-02 01:33:53,339 - main.py[DEBUG]: no di_report found in config. 2022-03-02 01:33:53,354 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:53,354 - stages.py[DEBUG]: Running module migrator () with frequency always 2022-03-02 01:33:53,354 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always 2022-03-02 01:33:53,354 - helpers.py[DEBUG]: Running config-migrator using lock () 2022-03-02 01:33:53,354 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names 2022-03-02 01:33:53,355 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully 2022-03-02 01:33:53,355 - stages.py[DEBUG]: Running module bootcmd () with frequency always 2022-03-02 01:33:53,355 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always 2022-03-02 01:33:53,355 - helpers.py[DEBUG]: Running config-bootcmd using lock () 2022-03-02 01:33:53,355 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration 2022-03-02 01:33:53,355 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully 2022-03-02 01:33:53,355 - stages.py[DEBUG]: Running module write-files () with frequency once-per-instance 2022-03-02 01:33:53,355 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance 2022-03-02 01:33:53,355 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_write_files - wb: [644] 25 bytes 2022-03-02 01:33:53,355 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_write_files (recursive=False) 2022-03-02 01:33:53,356 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_write_files (recursive=False) 2022-03-02 01:33:53,356 - helpers.py[DEBUG]: Running config-write-files using lock () 2022-03-02 01:33:53,356 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration 2022-03-02 01:33:53,356 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully 2022-03-02 01:33:53,356 - stages.py[DEBUG]: Running module resizefs () with frequency always 2022-03-02 01:33:53,356 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always 2022-03-02 01:33:53,356 - helpers.py[DEBUG]: Running config-resizefs using lock () 2022-03-02 01:33:53,369 - util.py[DEBUG]: Reading from /proc/3665/mountinfo (quiet=False) 2022-03-02 01:33:53,369 - util.py[DEBUG]: Read 3735 bytes from /proc/3665/mountinfo 2022-03-02 01:33:53,369 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/mapper/ocivolume-root mnt_point=/ path=/ 2022-03-02 01:33:53,369 - cc_resizefs.py[DEBUG]: Resizing / (xfs) using xfs_growfs / 2022-03-02 01:33:53,369 - subp.py[DEBUG]: Running command ('xfs_growfs', '/') with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:53,376 - util.py[DEBUG]: Resizing took 0.007 seconds 2022-03-02 01:33:53,376 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=xfs, val=True) 2022-03-02 01:33:53,376 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully 2022-03-02 01:33:53,376 - stages.py[DEBUG]: Running module rsyslog () with frequency once-per-instance 2022-03-02 01:33:53,376 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance 2022-03-02 01:33:53,377 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rsyslog - wb: [644] 25 bytes 2022-03-02 01:33:53,377 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rsyslog (recursive=False) 2022-03-02 01:33:53,378 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rsyslog (recursive=False) 2022-03-02 01:33:53,378 - helpers.py[DEBUG]: Running config-rsyslog using lock () 2022-03-02 01:33:53,378 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration 2022-03-02 01:33:53,378 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully 2022-03-02 01:33:53,378 - stages.py[DEBUG]: Running module users-groups () with frequency once-per-instance 2022-03-02 01:33:53,378 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance 2022-03-02 01:33:53,378 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_users_groups - wb: [644] 25 bytes 2022-03-02 01:33:53,378 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_users_groups (recursive=False) 2022-03-02 01:33:53,379 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_users_groups (recursive=False) 2022-03-02 01:33:53,379 - helpers.py[DEBUG]: Running config-users-groups using lock () 2022-03-02 01:33:53,382 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True) 2022-03-02 01:33:53,382 - util.py[DEBUG]: Read 479 bytes from /etc/os-release 2022-03-02 01:33:53,383 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True) 2022-03-02 01:33:53,383 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini 2022-03-02 01:33:53,383 - __init__.py[DEBUG]: Adding user opc 2022-03-02 01:33:53,383 - subp.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', 'opc', '--comment', 'Oracle Public Cloud User', '--groups', 'adm,systemd-journal', '--shell', '/bin/bash', '-m'] 2022-03-02 01:33:54,636 - subp.py[DEBUG]: Running command ['passwd', '-l', 'opc'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:54,653 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False) 2022-03-02 01:33:54,654 - util.py[DEBUG]: Read 4328 bytes from /etc/sudoers 2022-03-02 01:33:54,656 - util.py[DEBUG]: Restoring selinux mode for /etc/sudoers.d (recursive=False) 2022-03-02 01:33:54,657 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [440] 130 bytes 2022-03-02 01:33:54,658 - util.py[DEBUG]: Restoring selinux mode for /etc/sudoers.d/90-cloud-init-users (recursive=False) 2022-03-02 01:33:54,658 - util.py[DEBUG]: Restoring selinux mode for /etc/sudoers.d/90-cloud-init-users (recursive=False) 2022-03-02 01:33:54,659 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully 2022-03-02 01:33:54,659 - stages.py[DEBUG]: Running module ssh () with frequency once-per-instance 2022-03-02 01:33:54,659 - handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance 2022-03-02 01:33:54,659 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh - wb: [644] 24 bytes 2022-03-02 01:33:54,659 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh (recursive=False) 2022-03-02 01:33:54,660 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh (recursive=False) 2022-03-02 01:33:54,660 - helpers.py[DEBUG]: Running config-ssh using lock () 2022-03-02 01:33:54,662 - 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) 2022-03-02 01:33:54,855 - util.py[DEBUG]: Restoring selinux mode for /etc/ssh (recursive=True) 2022-03-02 01:33:54,856 - 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) 2022-03-02 01:33:54,862 - util.py[DEBUG]: Restoring selinux mode for /etc/ssh (recursive=True) 2022-03-02 01:33:54,863 - 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) 2022-03-02 01:33:54,867 - util.py[DEBUG]: Restoring selinux mode for /etc/ssh (recursive=True) 2022-03-02 01:33:54,868 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_rsa_key.pub (quiet=False) 2022-03-02 01:33:54,868 - util.py[DEBUG]: Read 581 bytes from /etc/ssh/ssh_host_rsa_key.pub 2022-03-02 01:33:54,868 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ecdsa_key.pub (quiet=False) 2022-03-02 01:33:54,868 - util.py[DEBUG]: Read 189 bytes from /etc/ssh/ssh_host_ecdsa_key.pub 2022-03-02 01:33:54,868 - util.py[DEBUG]: Reading from /etc/ssh/ssh_host_ed25519_key.pub (quiet=False) 2022-03-02 01:33:54,868 - util.py[DEBUG]: Read 109 bytes from /etc/ssh/ssh_host_ed25519_key.pub 2022-03-02 01:33:54,868 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2022-03-02 01:33:54,876 - util.py[DEBUG]: Read 4269 bytes from /etc/ssh/sshd_config 2022-03-02 01:33:54,877 - util.py[DEBUG]: Changing the ownership of /home/opc/.ssh to 1000:1000 2022-03-02 01:33:54,877 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh (recursive=False) 2022-03-02 01:33:54,877 - util.py[DEBUG]: Writing to /home/opc/.ssh/authorized_keys - wb: [600] 0 bytes 2022-03-02 01:33:54,878 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Changing the ownership of /home/opc/.ssh/authorized_keys to 1000:1000 2022-03-02 01:33:54,878 - util.py[DEBUG]: Reading from /home/opc/.ssh/authorized_keys (quiet=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Read 0 bytes from /home/opc/.ssh/authorized_keys 2022-03-02 01:33:54,878 - util.py[DEBUG]: Writing to /home/opc/.ssh/authorized_keys - wb: [600] 400 bytes 2022-03-02 01:33:54,878 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh (recursive=True) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2022-03-02 01:33:54,878 - util.py[DEBUG]: Read 4269 bytes from /etc/ssh/sshd_config 2022-03-02 01:33:54,879 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0 2022-03-02 01:33:54,879 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh (recursive=False) 2022-03-02 01:33:54,879 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 0 bytes 2022-03-02 01:33:54,879 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,879 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,879 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0 2022-03-02 01:33:54,880 - util.py[DEBUG]: Reading from /root/.ssh/authorized_keys (quiet=False) 2022-03-02 01:33:54,880 - util.py[DEBUG]: Read 0 bytes from /root/.ssh/authorized_keys 2022-03-02 01:33:54,880 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [600] 561 bytes 2022-03-02 01:33:54,880 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,880 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh/authorized_keys (recursive=False) 2022-03-02 01:33:54,880 - util.py[DEBUG]: Restoring selinux mode for /root/.ssh (recursive=True) 2022-03-02 01:33:54,880 - handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully 2022-03-02 01:33:54,880 - main.py[DEBUG]: Ran 7 modules with 0 failures 2022-03-02 01:33:54,881 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpvg8b3mhv) - w: [644] 517 bytes/chars 2022-03-02 01:33:54,881 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2022-03-02 01:33:54,881 - util.py[DEBUG]: Read 14 bytes from /proc/uptime 2022-03-02 01:33:54,881 - util.py[DEBUG]: cloud-init mode 'init' took 1.662 seconds (1.66) 2022-03-02 01:33:54,881 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources 2022-03-02 01:33:55,348 - util.py[DEBUG]: Cloud-init v. 21.1-7.0.2.el8_5.3 running 'modules:config' at Wed, 02 Mar 2022 01:33:55 +0000. Up 32.58 seconds. 2022-03-02 01:33:57,519 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:57,519 - stages.py[DEBUG]: Running module mounts () with frequency once-per-instance 2022-03-02 01:33:57,519 - handlers.py[DEBUG]: start: modules-config/config-mounts: running config-mounts with frequency once-per-instance 2022-03-02 01:33:57,519 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mounts - wb: [644] 25 bytes 2022-03-02 01:33:57,520 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mounts (recursive=False) 2022-03-02 01:33:57,522 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mounts (recursive=False) 2022-03-02 01:33:57,522 - helpers.py[DEBUG]: Running config-mounts using lock () 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: mounts configuration is [] 2022-03-02 01:33:57,522 - util.py[DEBUG]: Reading from /etc/fstab (quiet=False) 2022-03-02 01:33:57,522 - util.py[DEBUG]: Read 1383 bytes from /etc/fstab 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: Attempting to determine the real name of ephemeral0 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: changed default device ephemeral0 => None 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount ephemeral0 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: Attempting to determine the real name of swap 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: changed default device swap => None 2022-03-02 01:33:57,522 - cc_mounts.py[DEBUG]: Ignoring nonexistent default named mount swap 2022-03-02 01:33:57,522 - util.py[DEBUG]: Reading from /proc/meminfo (quiet=False) 2022-03-02 01:33:57,522 - util.py[DEBUG]: Read 1483 bytes from /proc/meminfo 2022-03-02 01:33:57,523 - cc_mounts.py[DEBUG]: suggest 8192.0 MB swap for 515186.875 MB memory with '32915.390625 MB' disk given max=None [max=8192.0 MB]' 2022-03-02 01:33:57,523 - util.py[DEBUG]: Reading from /proc/3751/mountinfo (quiet=False) 2022-03-02 01:33:57,523 - util.py[DEBUG]: Read 3735 bytes from /proc/3751/mountinfo 2022-03-02 01:33:57,523 - cc_mounts.py[DEBUG]: Creating swapfile in '/.swapfile' on fstype 'xfs' using 'fallocate' 2022-03-02 01:33:57,523 - subp.py[DEBUG]: Running command ['fallocate', '-l', '8192M', '/.swapfile'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,533 - util.py[DEBUG]: Restoring selinux mode for /.swapfile (recursive=False) 2022-03-02 01:33:57,533 - subp.py[DEBUG]: Running command ['mkswap', '/.swapfile'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,553 - util.py[DEBUG]: Setting up swap file took 0.030 seconds 2022-03-02 01:33:57,553 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False) 2022-03-02 01:33:57,553 - util.py[DEBUG]: Read 2895 bytes from /proc/mounts 2022-03-02 01:33:57,553 - util.py[DEBUG]: Fetched {'sysfs': {'fstype': 'sysfs', 'mountpoint': '/sys', 'opts': 'rw,seclabel,nosuid,nodev,noexec,relatime'}, 'proc': {'fstype': 'proc', 'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'devtmpfs': {'fstype': 'devtmpfs', 'mountpoint': '/dev', 'opts': 'rw,seclabel,nosuid,size=263707380k,nr_inodes=65926845,mode=755'}, 'securityfs': {'fstype': 'securityfs', 'mountpoint': '/sys/kernel/security', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'tmpfs': {'fstype': 'tmpfs', 'mountpoint': '/sys/fs/cgroup', 'opts': 'ro,seclabel,nosuid,nodev,noexec,mode=755'}, 'devpts': {'fstype': 'devpts', 'mountpoint': '/dev/pts', 'opts': 'rw,seclabel,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000'}, 'cgroup': {'fstype': 'cgroup', 'mountpoint': '/sys/fs/cgroup/perf_event', 'opts': 'rw,seclabel,nosuid,nodev,noexec,relatime,perf_event'}, 'pstore': {'fstype': 'pstore', 'mountpoint': '/sys/fs/pstore', 'opts': 'rw,seclabel,nosuid,nodev,noexec,relatime'}, 'efivarfs': {'fstype': 'efivarfs', 'mountpoint': '/sys/firmware/efi/efivars', 'opts': 'rw,nosuid,nodev,noexec,relatime'}, 'none': {'fstype': 'tracefs', 'mountpoint': '/sys/kernel/tracing', 'opts': 'rw,seclabel,relatime'}, 'configfs': {'fstype': 'configfs', 'mountpoint': '/sys/kernel/config', 'opts': 'rw,relatime'}, '/dev/mapper/ocivolume-root': {'fstype': 'xfs', 'mountpoint': '/', 'opts': 'rw,seclabel,relatime,attr2,inode64,logbufs=8,logbsize=32k,noquota'}, 'rpc_pipefs': {'fstype': 'rpc_pipefs', 'mountpoint': '/var/lib/nfs/rpc_pipefs', 'opts': 'rw,relatime'}, 'selinuxfs': {'fstype': 'selinuxfs', 'mountpoint': '/sys/fs/selinux', 'opts': 'rw,relatime'}, 'systemd-1': {'fstype': 'autofs', 'mountpoint': '/proc/sys/fs/binfmt_misc', 'opts': 'rw,relatime,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=48194'}, 'hugetlbfs': {'fstype': 'hugetlbfs', 'mountpoint': '/dev/hugepages', 'opts': 'rw,seclabel,relatime,pagesize=2M'}, 'mqueue': {'fstype': 'mqueue', 'mountpoint': '/dev/mqueue', 'opts': 'rw,seclabel,relatime'}, 'debugfs': {'fstype': 'debugfs', 'mountpoint': '/sys/kernel/debug', 'opts': 'rw,seclabel,relatime'}, '/dev/mapper/ocivolume-oled': {'fstype': 'xfs', 'mountpoint': '/var/oled', 'opts': 'rw,seclabel,relatime,attr2,inode64,logbufs=8,logbsize=32k,noquota'}, '/dev/sda2': {'fstype': 'xfs', 'mountpoint': '/boot', 'opts': 'rw,seclabel,relatime,attr2,inode64,logbufs=8,logbsize=32k,noquota'}, '/dev/sda1': {'fstype': 'vfat', 'mountpoint': '/boot/efi', 'opts': 'rw,relatime,fmask=0077,dmask=0077,codepage=437,iocharset=ascii,shortname=winnt,errors=remount-ro'}} mounts from proc 2022-03-02 01:33:57,553 - util.py[DEBUG]: Writing to /etc/fstab - wb: [644] 1431 bytes 2022-03-02 01:33:57,554 - util.py[DEBUG]: Restoring selinux mode for /etc/fstab (recursive=False) 2022-03-02 01:33:57,555 - util.py[DEBUG]: Restoring selinux mode for /etc/fstab (recursive=False) 2022-03-02 01:33:57,556 - cc_mounts.py[DEBUG]: Changes to fstab: ['+ /.swapfile none swap sw,comment=cloudconfig 0 0'] 2022-03-02 01:33:57,556 - subp.py[DEBUG]: Running command ['swapon', '-a'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,589 - cc_mounts.py[DEBUG]: Activate mounts: PASS:swapon -a 2022-03-02 01:33:57,589 - subp.py[DEBUG]: Running command ['mount', '-a'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,593 - cc_mounts.py[DEBUG]: Activate mounts: PASS:mount -a 2022-03-02 01:33:57,593 - subp.py[DEBUG]: Running command ['systemctl', 'daemon-reload'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,675 - cc_mounts.py[DEBUG]: Activate mounts: PASS:systemctl daemon-reload 2022-03-02 01:33:57,676 - handlers.py[DEBUG]: finish: modules-config/config-mounts: SUCCESS: config-mounts ran successfully 2022-03-02 01:33:57,676 - stages.py[DEBUG]: Running module locale () with frequency once-per-instance 2022-03-02 01:33:57,676 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance 2022-03-02 01:33:57,676 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_locale - wb: [644] 25 bytes 2022-03-02 01:33:57,677 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_locale (recursive=False) 2022-03-02 01:33:57,677 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_locale (recursive=False) 2022-03-02 01:33:57,678 - helpers.py[DEBUG]: Running config-locale using lock () 2022-03-02 01:33:57,681 - cc_locale.py[DEBUG]: Setting locale to en_US.UTF-8 2022-03-02 01:33:57,681 - util.py[DEBUG]: Reading from /etc/locale.conf (quiet=False) 2022-03-02 01:33:57,681 - util.py[DEBUG]: Read 19 bytes from /etc/locale.conf 2022-03-02 01:33:57,681 - util.py[DEBUG]: Writing to /etc/locale.conf - wb: [644] 17 bytes 2022-03-02 01:33:57,682 - util.py[DEBUG]: Restoring selinux mode for /etc/locale.conf (recursive=False) 2022-03-02 01:33:57,682 - util.py[DEBUG]: Restoring selinux mode for /etc/locale.conf (recursive=False) 2022-03-02 01:33:57,682 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully 2022-03-02 01:33:57,682 - stages.py[DEBUG]: Running module set-passwords () with frequency once-per-instance 2022-03-02 01:33:57,682 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance 2022-03-02 01:33:57,682 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_set_passwords - wb: [644] 25 bytes 2022-03-02 01:33:57,683 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_set_passwords (recursive=False) 2022-03-02 01:33:57,683 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_set_passwords (recursive=False) 2022-03-02 01:33:57,683 - helpers.py[DEBUG]: Running config-set-passwords using lock () 2022-03-02 01:33:57,683 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2022-03-02 01:33:57,684 - util.py[DEBUG]: Read 4269 bytes from /etc/ssh/sshd_config 2022-03-02 01:33:57,684 - ssh_util.py[DEBUG]: line 70: option PasswordAuthentication updated yes -> no 2022-03-02 01:33:57,684 - util.py[DEBUG]: Writing to /etc/ssh/sshd_config - wb: [600] 4268 bytes 2022-03-02 01:33:57,684 - util.py[DEBUG]: Restoring selinux mode for /etc/ssh/sshd_config (recursive=False) 2022-03-02 01:33:57,684 - util.py[DEBUG]: Restoring selinux mode for /etc/ssh/sshd_config (recursive=False) 2022-03-02 01:33:57,684 - subp.py[DEBUG]: Running command ['service', 'sshd', 'restart'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:57,712 - cc_set_passwords.py[DEBUG]: Restarted the SSH daemon. 2022-03-02 01:33:57,712 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully 2022-03-02 01:33:57,712 - stages.py[DEBUG]: Running module yum-add-repo () with frequency once-per-instance 2022-03-02 01:33:57,712 - handlers.py[DEBUG]: start: modules-config/config-yum-add-repo: running config-yum-add-repo with frequency once-per-instance 2022-03-02 01:33:57,712 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_yum_add_repo - wb: [644] 25 bytes 2022-03-02 01:33:57,713 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_yum_add_repo (recursive=False) 2022-03-02 01:33:57,713 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_yum_add_repo (recursive=False) 2022-03-02 01:33:57,714 - helpers.py[DEBUG]: Running config-yum-add-repo using lock () 2022-03-02 01:33:57,714 - cc_yum_add_repo.py[DEBUG]: Skipping module named yum-add-repo, no 'yum_repos' configuration found 2022-03-02 01:33:57,714 - handlers.py[DEBUG]: finish: modules-config/config-yum-add-repo: SUCCESS: config-yum-add-repo ran successfully 2022-03-02 01:33:57,714 - stages.py[DEBUG]: Running module package-update-upgrade-install () with frequency once-per-instance 2022-03-02 01:33:57,714 - handlers.py[DEBUG]: start: modules-config/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance 2022-03-02 01:33:57,714 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_package_update_upgrade_install - wb: [644] 25 bytes 2022-03-02 01:33:57,714 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_package_update_upgrade_install (recursive=False) 2022-03-02 01:33:57,715 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_package_update_upgrade_install (recursive=False) 2022-03-02 01:33:57,715 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock () 2022-03-02 01:33:57,715 - handlers.py[DEBUG]: finish: modules-config/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully 2022-03-02 01:33:57,715 - stages.py[DEBUG]: Running module timezone () with frequency once-per-instance 2022-03-02 01:33:57,715 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance 2022-03-02 01:33:57,715 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_timezone - wb: [644] 25 bytes 2022-03-02 01:33:57,716 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_timezone (recursive=False) 2022-03-02 01:33:57,716 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_timezone (recursive=False) 2022-03-02 01:33:57,716 - helpers.py[DEBUG]: Running config-timezone using lock () 2022-03-02 01:33:57,717 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified 2022-03-02 01:33:57,717 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully 2022-03-02 01:33:57,717 - stages.py[DEBUG]: Running module ntp () with frequency once-per-instance 2022-03-02 01:33:57,717 - handlers.py[DEBUG]: start: modules-config/config-ntp: running config-ntp with frequency once-per-instance 2022-03-02 01:33:57,717 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ntp - wb: [644] 24 bytes 2022-03-02 01:33:57,717 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ntp (recursive=False) 2022-03-02 01:33:57,718 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ntp (recursive=False) 2022-03-02 01:33:57,718 - helpers.py[DEBUG]: Running config-ntp using lock () 2022-03-02 01:33:57,718 - cc_ntp.py[DEBUG]: Skipping module named ntp, not present or disabled by cfg 2022-03-02 01:33:57,718 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully 2022-03-02 01:33:57,718 - stages.py[DEBUG]: Running module puppet () with frequency once-per-instance 2022-03-02 01:33:57,718 - handlers.py[DEBUG]: start: modules-config/config-puppet: running config-puppet with frequency once-per-instance 2022-03-02 01:33:57,718 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_puppet - wb: [644] 25 bytes 2022-03-02 01:33:57,718 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_puppet (recursive=False) 2022-03-02 01:33:57,719 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_puppet (recursive=False) 2022-03-02 01:33:57,719 - helpers.py[DEBUG]: Running config-puppet using lock () 2022-03-02 01:33:57,719 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found 2022-03-02 01:33:57,719 - handlers.py[DEBUG]: finish: modules-config/config-puppet: SUCCESS: config-puppet ran successfully 2022-03-02 01:33:57,719 - stages.py[DEBUG]: Running module chef () with frequency always 2022-03-02 01:33:57,719 - handlers.py[DEBUG]: start: modules-config/config-chef: running config-chef with frequency always 2022-03-02 01:33:57,719 - helpers.py[DEBUG]: Running config-chef using lock () 2022-03-02 01:33:57,719 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration 2022-03-02 01:33:57,719 - handlers.py[DEBUG]: finish: modules-config/config-chef: SUCCESS: config-chef ran successfully 2022-03-02 01:33:57,719 - stages.py[DEBUG]: Running module salt-minion () with frequency once-per-instance 2022-03-02 01:33:57,720 - handlers.py[DEBUG]: start: modules-config/config-salt-minion: running config-salt-minion with frequency once-per-instance 2022-03-02 01:33:57,720 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_salt_minion - wb: [644] 25 bytes 2022-03-02 01:33:57,720 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_salt_minion (recursive=False) 2022-03-02 01:33:57,720 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_salt_minion (recursive=False) 2022-03-02 01:33:57,721 - helpers.py[DEBUG]: Running config-salt-minion using lock () 2022-03-02 01:33:57,721 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration 2022-03-02 01:33:57,721 - handlers.py[DEBUG]: finish: modules-config/config-salt-minion: SUCCESS: config-salt-minion ran successfully 2022-03-02 01:33:57,721 - stages.py[DEBUG]: Running module mcollective () with frequency once-per-instance 2022-03-02 01:33:57,721 - handlers.py[DEBUG]: start: modules-config/config-mcollective: running config-mcollective with frequency once-per-instance 2022-03-02 01:33:57,721 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mcollective - wb: [644] 24 bytes 2022-03-02 01:33:57,721 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mcollective (recursive=False) 2022-03-02 01:33:57,722 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_mcollective (recursive=False) 2022-03-02 01:33:57,722 - helpers.py[DEBUG]: Running config-mcollective using lock () 2022-03-02 01:33:57,722 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration 2022-03-02 01:33:57,722 - handlers.py[DEBUG]: finish: modules-config/config-mcollective: SUCCESS: config-mcollective ran successfully 2022-03-02 01:33:57,722 - stages.py[DEBUG]: Running module disable-ec2-metadata () with frequency always 2022-03-02 01:33:57,722 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always 2022-03-02 01:33:57,722 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock () 2022-03-02 01:33:57,722 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled 2022-03-02 01:33:57,722 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully 2022-03-02 01:33:57,722 - stages.py[DEBUG]: Running module runcmd () with frequency once-per-instance 2022-03-02 01:33:57,722 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance 2022-03-02 01:33:57,722 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_runcmd - wb: [644] 25 bytes 2022-03-02 01:33:57,723 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_runcmd (recursive=False) 2022-03-02 01:33:57,723 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_runcmd (recursive=False) 2022-03-02 01:33:57,723 - helpers.py[DEBUG]: Running config-runcmd using lock () 2022-03-02 01:33:57,723 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration 2022-03-02 01:33:57,723 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully 2022-03-02 01:33:57,724 - main.py[DEBUG]: Ran 13 modules with 0 failures 2022-03-02 01:33:57,724 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmp6vl2tp4v) - w: [644] 545 bytes/chars 2022-03-02 01:33:57,724 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2022-03-02 01:33:57,724 - util.py[DEBUG]: Read 14 bytes from /proc/uptime 2022-03-02 01:33:57,724 - util.py[DEBUG]: cloud-init mode 'modules' took 2.421 seconds (2.42) 2022-03-02 01:33:57,724 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config 2022-03-02 01:33:57,991 - util.py[DEBUG]: Cloud-init v. 21.1-7.0.2.el8_5.3 running 'modules:final' at Wed, 02 Mar 2022 01:33:57 +0000. Up 35.23 seconds. 2022-03-02 01:33:58,000 - stages.py[DEBUG]: Using distro class 2022-03-02 01:33:58,000 - stages.py[DEBUG]: Running module rightscale_userdata () with frequency once-per-instance 2022-03-02 01:33:58,001 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance 2022-03-02 01:33:58,001 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rightscale_userdata - wb: [644] 24 bytes 2022-03-02 01:33:58,002 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rightscale_userdata (recursive=False) 2022-03-02 01:33:58,003 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_rightscale_userdata (recursive=False) 2022-03-02 01:33:58,003 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock () 2022-03-02 01:33:58,003 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata 2022-03-02 01:33:58,003 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully 2022-03-02 01:33:58,003 - stages.py[DEBUG]: Running module scripts-per-once () with frequency once 2022-03-02 01:33:58,003 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once 2022-03-02 01:33:58,003 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 25 bytes 2022-03-02 01:33:58,004 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem/config_scripts_per_once.once (recursive=False) 2022-03-02 01:33:58,004 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem/config_scripts_per_once.once (recursive=False) 2022-03-02 01:33:58,004 - helpers.py[DEBUG]: Running config-scripts-per-once using lock () 2022-03-02 01:33:58,004 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully 2022-03-02 01:33:58,004 - stages.py[DEBUG]: Running module scripts-per-boot () with frequency always 2022-03-02 01:33:58,004 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always 2022-03-02 01:33:58,004 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock () 2022-03-02 01:33:58,005 - subp.py[DEBUG]: Running command ['/var/lib/cloud/scripts/per-boot/oci.sh'] with allowed return codes [0] (shell=False, capture=False) 2022-03-02 01:33:58,015 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully 2022-03-02 01:33:58,015 - stages.py[DEBUG]: Running module scripts-per-instance () with frequency once-per-instance 2022-03-02 01:33:58,015 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance 2022-03-02 01:33:58,016 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_per_instance - wb: [644] 25 bytes 2022-03-02 01:33:58,016 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_per_instance (recursive=False) 2022-03-02 01:33:58,017 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_per_instance (recursive=False) 2022-03-02 01:33:58,017 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock () 2022-03-02 01:33:58,017 - subp.py[DEBUG]: Running command ['/var/lib/cloud/scripts/per-instance/oci.sh'] with allowed return codes [0] (shell=False, capture=False) 2022-03-02 01:33:58,026 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully 2022-03-02 01:33:58,026 - stages.py[DEBUG]: Running module scripts-user () with frequency once-per-instance 2022-03-02 01:33:58,026 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance 2022-03-02 01:33:58,026 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_user - wb: [644] 25 bytes 2022-03-02 01:33:58,027 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_user (recursive=False) 2022-03-02 01:33:58,027 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_scripts_user (recursive=False) 2022-03-02 01:33:58,028 - helpers.py[DEBUG]: Running config-scripts-user using lock () 2022-03-02 01:33:58,028 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully 2022-03-02 01:33:58,028 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints () with frequency once-per-instance 2022-03-02 01:33:58,028 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance 2022-03-02 01:33:58,028 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh_authkey_fingerprints - wb: [644] 24 bytes 2022-03-02 01:33:58,028 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh_authkey_fingerprints (recursive=False) 2022-03-02 01:33:58,029 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_ssh_authkey_fingerprints (recursive=False) 2022-03-02 01:33:58,029 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock () 2022-03-02 01:33:58,029 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False) 2022-03-02 01:33:58,029 - util.py[DEBUG]: Read 4268 bytes from /etc/ssh/sshd_config 2022-03-02 01:33:58,030 - util.py[DEBUG]: Restoring selinux mode for /home/opc/.ssh (recursive=True) 2022-03-02 01:33:58,031 - util.py[DEBUG]: Reading from /home/opc/.ssh/authorized_keys (quiet=False) 2022-03-02 01:33:58,031 - util.py[DEBUG]: Read 400 bytes from /home/opc/.ssh/authorized_keys 2022-03-02 01:33:58,031 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints ran successfully 2022-03-02 01:33:58,031 - stages.py[DEBUG]: Running module keys-to-console () with frequency once-per-instance 2022-03-02 01:33:58,031 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance 2022-03-02 01:33:58,032 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_keys_to_console - wb: [644] 25 bytes 2022-03-02 01:33:58,032 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_keys_to_console (recursive=False) 2022-03-02 01:33:58,032 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_keys_to_console (recursive=False) 2022-03-02 01:33:58,033 - helpers.py[DEBUG]: Running config-keys-to-console using lock () 2022-03-02 01:33:58,033 - subp.py[DEBUG]: Running command ['/usr/libexec/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True) 2022-03-02 01:33:58,044 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully 2022-03-02 01:33:58,044 - stages.py[DEBUG]: Running module phone-home () with frequency once-per-instance 2022-03-02 01:33:58,044 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance 2022-03-02 01:33:58,044 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_phone_home - wb: [644] 25 bytes 2022-03-02 01:33:58,045 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_phone_home (recursive=False) 2022-03-02 01:33:58,045 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/sem/config_phone_home (recursive=False) 2022-03-02 01:33:58,045 - helpers.py[DEBUG]: Running config-phone-home using lock () 2022-03-02 01:33:58,046 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found 2022-03-02 01:33:58,046 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully 2022-03-02 01:33:58,046 - stages.py[DEBUG]: Running module final-message () with frequency always 2022-03-02 01:33:58,046 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always 2022-03-02 01:33:58,046 - helpers.py[DEBUG]: Running config-final-message using lock () 2022-03-02 01:33:58,046 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2022-03-02 01:33:58,046 - util.py[DEBUG]: Read 14 bytes from /proc/uptime 2022-03-02 01:33:58,048 - util.py[DEBUG]: Cloud-init v. 21.1-7.0.2.el8_5.3 finished at Wed, 02 Mar 2022 01:33:58 +0000. Datasource DataSourceOracle. Up 35.32 seconds 2022-03-02 01:33:58,048 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 64 bytes 2022-03-02 01:33:58,049 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/boot-finished (recursive=False) 2022-03-02 01:33:58,049 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances/ocid1.instance.oc1.ap-tokyo-1.anxhiljrnid5cgac2suc5hnz4tkxdyv7aa6vo65haslzkijimbl3jnud63dq/boot-finished (recursive=False) 2022-03-02 01:33:58,050 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully 2022-03-02 01:33:58,050 - main.py[DEBUG]: Ran 9 modules with 0 failures 2022-03-02 01:33:58,050 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/status.json (via temporary file /var/lib/cloud/data/tmpp0pcv3l3) - w: [644] 573 bytes/chars 2022-03-02 01:33:58,050 - atomic_helper.py[DEBUG]: Atomically writing to file /var/lib/cloud/data/result.json (via temporary file /var/lib/cloud/data/tmp925agarm) - w: [644] 67 bytes/chars 2022-03-02 01:33:58,050 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json' 2022-03-02 01:33:58,050 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False) 2022-03-02 01:33:58,050 - util.py[DEBUG]: Read 14 bytes from /proc/uptime 2022-03-02 01:33:58,050 - util.py[DEBUG]: cloud-init mode 'modules' took 0.103 seconds (0.10) 2022-03-02 01:33:58,050 - handlers.py[DEBUG]: finish: modules-final: SUCCESS: running modules for final