2024-02-21 15:17:28,776 INFO subiquity:200 Starting Subiquity server revision 6 of snap /snap/ubuntu-desktop-bootstrap/6 of version 0+git.15cde5fb 2024-02-21 15:17:28,776 INFO subiquity:204 Arguments passed: ['/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-bootstrap/6/etc/subiquity/postinst.d'] 2024-02-21 15:17:28,776 DEBUG subiquity:205 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz --- quiet splash\n', _tokens={'splash', '---', 'quiet'}, _values={'BOOT_IMAGE': '/casper/vmlinuz'}) 2024-02-21 15:17:28,776 DEBUG subiquity:206 Environment: environ({'SNAP_REVISION': '6', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-desktop-bootstrap/common', 'PATH_ORIG': '/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'MEMORY_PRESSURE_WRITE': 'c29tZSAyMDAwMDAgMjAwMDAwMAA=', 'SNAP_INSTANCE_KEY': '', 'SNAP_EUID': '0', 'PWD': '/snap/ubuntu-desktop-bootstrap/6/bin/subiquity', 'SYSTEMD_EXEC_PID': '3692', 'SNAP_CONTEXT': 'dyAs3qOGNrrhrosnsC47qx6tt4N-MxzNX0zcCndVvGxzRE9ZtoaH', 'LANG': 'C.UTF-8', 'MEMORY_PRESSURE_WATCH': '/sys/fs/cgroup/system.slice/snap.ubuntu-desktop-bootstrap.subiquity-server.service/memory.pressure', 'SNAP_ARCH': 'amd64', 'PY3OR2_PYTHON': '/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-desktop-bootstrap', 'SNAP_USER_DATA': '/root/snap/ubuntu-desktop-bootstrap/6', 'INVOCATION_ID': 'ffd9123e7f6e474a96a2cf55f7a29ad0', 'SNAP_REEXEC': '', 'SNAP_UID': '0', 'LD_LIBRARY_PATH_ORIG': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-bootstrap/6/lib:/snap/ubuntu-desktop-bootstrap/6/usr/lib:/snap/ubuntu-desktop-bootstrap/6/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/6/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', 'USER': 'root', 'PYTHON': '/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-desktop-bootstrap/6', 'SNAP_COMMON': '/var/snap/ubuntu-desktop-bootstrap/common', 'SNAP_VERSION': '0+git.15cde5fb', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': 'dyAs3qOGNrrhrosnsC47qx6tt4N-MxzNX0zcCndVvGxzRE9ZtoaH', 'SNAP_DATA': '/var/snap/ubuntu-desktop-bootstrap/6', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-bootstrap/6/lib:/snap/ubuntu-desktop-bootstrap/6/usr/lib:/snap/ubuntu-desktop-bootstrap/6/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/6/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-desktop-bootstrap', 'JOURNAL_STREAM': '8:15049', 'PATH': '/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'SUBIQUITY_ROOT': '/snap/ubuntu-desktop-bootstrap/6/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-desktop-bootstrap/6', '_': '/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/share/apport'}) 2024-02-21 15:17:28,777 DEBUG asyncio:54 Using selector: EpollSelector 2024-02-21 15:17:28,778 DEBUG subiquitycore.prober:33 Prober() init finished, data:None 2024-02-21 15:17:28,870 DEBUG curtin:95 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True) 2024-02-21 15:17:28,883 DEBUG subiquitycore.netplan:113 config for zz-all-en = {'dhcp4': True, 'match': {'name': 'en*'}} 2024-02-21 15:17:28,883 DEBUG subiquitycore.netplan:113 config for zz-all-eth = {'dhcp4': True, 'match': {'name': 'eth*'}} 2024-02-21 15:17:28,901 DEBUG subiquitycore.utils:118 arun_command called: ['cloud-init', 'status', '--wait'] 2024-02-21 15:17:29,121 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 2024-02-21 15:17:29,122 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init... 2024-02-21 15:17:30,125 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 2024-02-21 15:17:30,125 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init... 2024-02-21 15:17:31,060 DEBUG subiquitycore.utils:132 arun_command ['cloud-init', 'status', '--wait'] exited with code 0 2024-02-21 15:17:31,061 DEBUG subiquity.server.server:569 waited 2.159897804260254s for cloud-init 2024-02-21 15:17:31,062 DEBUG subiquity.cloudinit:14 Loaded cloud config from /run/cloud-init/combined-cloud-config.json 2024-02-21 15:17:31,062 DEBUG subiquity.server.server:553 no autoinstall found in cloud-config 2024-02-21 15:17:31,062 DEBUG subiquitycore.utils:76 run_command called: ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] 2024-02-21 15:17:31,069 DEBUG subiquitycore.utils:95 run_command ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] exited with code 255 2024-02-21 15:17:31,069 DEBUG subiquitycore.ssh:52 ssh-keygen -lf /home/installer/.ssh/authorized_keys failed '/home/installer/.ssh/authorized_keys is not a public key file.\r\n' 2024-02-21 15:17:31,069 DEBUG subiquitycore.utils:76 run_command called: chpasswd 2024-02-21 15:17:31,367 DEBUG subiquitycore.utils:95 run_command chpasswd exited with code 0 2024-02-21 15:17:31,368 DEBUG subiquity.server.server:470 load_autoinstall_config only_early True file None 2024-02-21 15:17:31,368 DEBUG subiquity.server.server:470 load_autoinstall_config only_early False file None 2024-02-21 15:17:31,370 DEBUG subiquitycore.core:118 starting controllers 2024-02-21 15:17:31,372 DEBUG subiquity.server.controllers.kernel:63 Using kernel linux-generic-hwe-22.04 due to /etc/subiquity/kernel-meta-package 2024-02-21 15:17:31,383 DEBUG subiquity.models.source:93 loaded 2 sources from '/cdrom/casper/install-sources.yaml' 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp2s0'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.1.116/24'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'} 2024-02-21 15:17:31,385 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5e85:7eff:fe45:7845/64'} 2024-02-21 15:17:31,385 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2024-02-21 15:17:31,542 DEBUG subiquitycore.models.network:475 new_link 1 lo lo 2024-02-21 15:17:31,543 DEBUG subiquitycore.models.network:477 ignoring based on type 2024-02-21 15:17:31,543 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp2s0'} 2024-02-21 15:17:31,692 DEBUG subiquitycore.models.network:475 new_link 2 enp2s0 eth 2024-02-21 15:17:31,693 DEBUG subiquitycore.models.network:506 new_link 2 enp2s0 with config {'dhcp4': True} 2024-02-21 15:17:31,693 DEBUG root:30 start: subiquity/Network/_send_update: NEW enp2s0 2024-02-21 15:17:31,693 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: NEW enp2s0 2024-02-21 15:17:31,693 DEBUG probert.network:672 link_change NEW {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2024-02-21 15:17:31,846 DEBUG subiquitycore.models.network:475 new_link 3 wlp0s12f0 wlan 2024-02-21 15:17:31,847 DEBUG subiquitycore.models.network:506 new_link 3 wlp0s12f0 with config {} 2024-02-21 15:17:31,847 DEBUG subiquity.server.controllers.network:100 maybe_start_install_wpasupplicant 2024-02-21 15:17:31,847 DEBUG root:30 start: subiquity/Network/_send_update: NEW wlp0s12f0 2024-02-21 15:17:31,847 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: NEW wlp0s12f0 2024-02-21 15:17:31,848 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2024-02-21 15:17:31,848 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.1.116/24'} 2024-02-21 15:17:31,848 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp2s0 2024-02-21 15:17:31,848 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp2s0 2024-02-21 15:17:31,848 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'} 2024-02-21 15:17:31,848 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5e85:7eff:fe45:7845/64'} 2024-02-21 15:17:31,849 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp2s0 2024-02-21 15:17:31,849 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp2s0 2024-02-21 15:17:31,849 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2024-02-21 15:17:31,874 DEBUG pyroute2.ndb.135697653631680.sources.localhost:74 init 2024-02-21 15:17:31,875 DEBUG pyroute2.ndb.135697653631680.sources.localhost:384 starting the source 2024-02-21 15:17:31,875 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:74 init 2024-02-21 15:17:31,875 DEBUG pyroute2.ndb.135697653631680.sources.localhost:74 connecting 2024-02-21 15:17:31,876 DEBUG pyroute2.ndb.135697653631680.sources.localhost:74 loading 2024-02-21 15:17:31,877 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:384 starting the source 2024-02-21 15:17:31,877 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:74 connecting 2024-02-21 15:17:31,880 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:74 loading 2024-02-21 15:17:31,902 DEBUG pyroute2.ndb.135697653631680.sources.localhost:74 running 2024-02-21 15:17:31,905 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:74 running 2024-02-21 15:17:31,906 DEBUG pyroute2.ndb.135697653631680.sources.localhost:399 source shutdown 2024-02-21 15:17:31,907 DEBUG pyroute2.ndb.135697653631680.sources.localhost:374 sync 2024-02-21 15:17:31,907 DEBUG pyroute2.ndb.135697653631680.sources.localhost:369 shutdown handled by the main thread 2024-02-21 15:17:31,907 DEBUG pyroute2.ndb.135697653631680.sources.localhost:74 stopped 2024-02-21 15:17:31,907 DEBUG pyroute2.ndb.135697653631680.main:851 flush DB for the target localhost 2024-02-21 15:17:31,908 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:399 source shutdown 2024-02-21 15:17:31,909 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:374 sync 2024-02-21 15:17:31,909 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:369 shutdown handled by the main thread 2024-02-21 15:17:31,909 DEBUG pyroute2.ndb.135697653631680.sources.localhost/nsmanager:74 stopped 2024-02-21 15:17:31,909 DEBUG pyroute2.ndb.135697653631680.main:851 flush DB for the target localhost/nsmanager 2024-02-21 15:17:31,910 DEBUG subiquitycore.controllers.network:102 default routes True 2024-02-21 15:17:31,910 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.1.0/24', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.1.116', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.1.255', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::5e85:7eff:fe45:7845', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2} 2024-02-21 15:17:31,911 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_INTERFACE', 'ifindex': -1} 2024-02-21 15:17:31,912 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_INTERFACE', 'ifindex': 3, 'ssids': []} 2024-02-21 15:17:31,927 DEBUG subiquitycore.core:121 controllers started 2024-02-21 15:17:31,927 INFO root:30 start: subiquity/apply_autoinstall_config: 2024-02-21 15:17:31,928 DEBUG root:30 start: subiquity/Early/apply_autoinstall_config: 2024-02-21 15:17:31,928 DEBUG root:30 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,929 DEBUG root:30 start: subiquity/Reporting/apply_autoinstall_config: 2024-02-21 15:17:31,929 DEBUG root:30 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,929 DEBUG root:30 start: subiquity/Error/apply_autoinstall_config: 2024-02-21 15:17:31,929 DEBUG root:30 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,930 DEBUG root:30 start: subiquity/Userdata/apply_autoinstall_config: 2024-02-21 15:17:31,930 DEBUG root:30 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,930 DEBUG subiquity.models.subiquity:256 model userdata for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'snaplist', 'packages', 'drivers', 'locale', 'ssh'} 2024-02-21 15:17:31,930 DEBUG root:30 start: subiquity/Package/apply_autoinstall_config: 2024-02-21 15:17:31,931 DEBUG root:30 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,931 DEBUG subiquity.models.subiquity:256 model packages for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'snaplist', 'drivers', 'locale', 'ssh'} 2024-02-21 15:17:31,931 DEBUG root:30 start: subiquity/Debconf/apply_autoinstall_config: 2024-02-21 15:17:31,931 DEBUG root:30 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,931 DEBUG subiquity.models.subiquity:256 model debconf_selections for install stage is configured, to go {'filesystem', 'source', 'kernel', 'keyboard', 'proxy'} 2024-02-21 15:17:31,931 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Locale as interactive 2024-02-21 15:17:31,931 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Refresh as interactive 2024-02-21 15:17:31,932 DEBUG root:30 start: subiquity/Kernel/apply_autoinstall_config: 2024-02-21 15:17:31,932 DEBUG root:30 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,932 DEBUG subiquity.models.subiquity:256 model kernel for install stage is configured, to go {'keyboard', 'filesystem', 'source', 'proxy'} 2024-02-21 15:17:31,932 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Integrity as interactive 2024-02-21 15:17:31,932 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Keyboard as interactive 2024-02-21 15:17:31,932 DEBUG root:30 start: subiquity/Zdev/apply_autoinstall_config: 2024-02-21 15:17:31,932 DEBUG root:30 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Source as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Network as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping UbuntuPro as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Proxy as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Mirror as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Filesystem as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Identity as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping SSH as interactive 2024-02-21 15:17:31,933 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping SnapList as interactive 2024-02-21 15:17:31,934 DEBUG root:30 start: subiquity/Ad/apply_autoinstall_config: 2024-02-21 15:17:31,934 DEBUG root:30 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Codecs as interactive 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Drivers as interactive 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping OEM as interactive 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping TimeZone as interactive 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Install as interactive 2024-02-21 15:17:31,934 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Updates as interactive 2024-02-21 15:17:31,934 DEBUG root:30 start: subiquity/Late/apply_autoinstall_config: 2024-02-21 15:17:31,935 DEBUG root:30 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,935 DEBUG subiquity.server.server:461 apply_autoinstall_config: skipping Shutdown as interactive 2024-02-21 15:17:31,935 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2024-02-21 15:17:31,935 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'snaplist', 'drivers', 'ssh'} 2024-02-21 15:17:31,935 DEBUG root:30 start: subiquity/Refresh/configure_snapd: 2024-02-21 15:17:31,935 DEBUG root:30 start: subiquity/Refresh/configure_snapd/get_details: 2024-02-21 15:17:31,937 DEBUG root:30 start: subiquity/Refresh/check_for_update: 2024-02-21 15:17:31,937 DEBUG subiquitycore.utils:118 arun_command called: ['/usr/sbin/realm', 'discover'] 2024-02-21 15:17:31,954 DEBUG root:30 start: subiquity/OEM/load_metapackages_list: 2024-02-21 15:17:31,954 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_confirmation: 2024-02-21 15:17:31,954 DEBUG root:30 start: subiquity/Install/install: 2024-02-21 15:17:31,955 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:17:31,970 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/snaps/ubuntu-desktop-bootstrap HTTP/1.1" 200 1578 2024-02-21 15:17:31,983 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:17:31,983 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2024-02-21 15:17:31,983 DEBUG subiquity.server.pkghelper:65 checking if wpasupplicant is available 2024-02-21 15:17:39,923 DEBUG subiquity.server.pkghelper:71 wpasupplicant already installed 2024-02-21 15:17:39,924 DEBUG subiquity.server.controllers.snaplist:79 loading list of snaps 2024-02-21 15:17:40,032 DEBUG root:30 start: subiquity/Filesystem/_probe: 2024-02-21 15:17:40,034 DEBUG subiquity.server.controllers.network:112 wlan_support_install_finished PackageInstallState.DONE 2024-02-21 15:17:40,037 DEBUG root:30 start: subiquity/Refresh/check_for_update: 2024-02-21 15:17:40,038 DEBUG root:30 start: subiquity/SnapList/loader: 2024-02-21 15:17:40,038 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2024-02-21 15:17:40,043 DEBUG asyncio:54 Using selector: EpollSelector 2024-02-21 15:17:40,044 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:17:40,110 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:17:40,111 DEBUG root:30 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '0+git.15cde5fb' 2024-02-21 15:17:40,112 DEBUG subiquity.server.controllers.refresh:139 snap tracking , not resetting based on .disk/info 2024-02-21 15:17:40,112 DEBUG root:30 finish: subiquity/Refresh/configure_snapd: SUCCESS: 2024-02-21 15:17:40,112 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:17:40,119 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:17:40,119 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'HUAWEI-V3FCRP', 'no status')]} 2024-02-21 15:17:40,120 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:17:40,135 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-02-21 15:17:40,137 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-02-21 15:17:40,139 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:17:40,140 DEBUG probert.network:585 event for link_change: CHANGE {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2024-02-21 15:17:40,140 DEBUG probert.network:672 link_change CHANGE {'ifindex': 3, 'flags': 4099, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'wlp0s12f0'} 2024-02-21 15:17:40,140 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE wlp0s12f0 2024-02-21 15:17:40,140 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE wlp0s12f0 2024-02-21 15:17:40,141 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2024-02-21 15:17:40,141 DEBUG root:30 start: subiquity/SnapList/loader/list: 2024-02-21 15:17:40,141 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:17:40,153 DEBUG probert.dasd:134 Probing DASD devies 2024-02-21 15:17:40,154 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2024-02-21 15:17:40,167 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:17:40,167 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2024-02-21 15:17:40,170 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:17:40,208 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:17:40,209 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'HUAWEI-V3FCRP', 'no status')]} 2024-02-21 15:17:40,210 INFO root:30 start: subiquity/Meta/client_variant_POST: 2024-02-21 15:17:40,211 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2024-02-21 15:17:40,211 DEBUG root:30 start: subiquity/Refresh/GET: 2024-02-21 15:17:40,212 DEBUG subiquitycore.utils:132 arun_command ['/usr/sbin/realm', 'discover'] exited with code 1 2024-02-21 15:17:40,223 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:17:40,223 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:17:40,227 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2024-02-21 15:17:40,227 DEBUG subiquity.models.subiquity:256 model ubuntu_pro for postinstall stage is configured, to go {'timezone', 'identity', 'snaplist', 'drivers', 'network', 'ssh', 'codecs'} 2024-02-21 15:17:40,228 DEBUG subiquity.models.subiquity:256 model proxy for install stage is configured, to go {'filesystem', 'mirror', 'source', 'network', 'keyboard'} 2024-02-21 15:17:40,228 DEBUG subiquity.models.subiquity:256 model mirror for install stage is configured, to go {'keyboard', 'filesystem', 'source', 'network'} 2024-02-21 15:17:40,228 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'timezone', 'identity', 'snaplist', 'drivers', 'network', 'codecs'} 2024-02-21 15:17:40,228 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'timezone', 'identity', 'drivers', 'network', 'codecs'} 2024-02-21 15:17:40,228 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-02-21 15:17:40,461 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None 2024-02-21 15:17:40,464 DEBUG root:30 finish: subiquity/SnapList/loader/list: SUCCESS: 2024-02-21 15:17:40,464 DEBUG subiquity.server.controllers.snaplist:92 fetched list of 22 snaps 2024-02-21 15:17:40,465 DEBUG root:30 start: subiquity/SnapList/loader/fetch/microk8s: 2024-02-21 15:17:40,668 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 59 2024-02-21 15:17:40,669 DEBUG subiquity.server.controllers.refresh:201 check_for_update received [] 2024-02-21 15:17:40,669 DEBUG root:30 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available 2024-02-21 15:17:40,670 DEBUG root:30 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "0+git.15cde5fb", "ne... 2024-02-21 15:17:40,964 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None 2024-02-21 15:17:40,980 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 2024-02-21 15:17:40,981 DEBUG root:30 start: subiquity/SnapList/loader/fetch/nextcloud: 2024-02-21 15:17:41,281 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None 2024-02-21 15:17:41,300 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 2024-02-21 15:17:41,301 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wekan: 2024-02-21 15:17:41,305 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sda2` exited with result: 0 2024-02-21 15:17:41,305 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Filesystem volume name: 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Last mounted on: / 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Filesystem UUID: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:17:41,305 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Inode count: 7749632 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Block count: 30982912 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Reserved block count: 1549145 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Overhead clusters: 632916 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Free blocks: 28503633 2024-02-21 15:17:41,306 DEBUG probert.utils:50 Free inodes: 7665667 2024-02-21 15:17:41,307 DEBUG probert.utils:50 First block: 0 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:17:41,307 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 08:00:42 2024 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Last mount time: Wed Feb 21 08:05:08 2024 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Last write time: Wed Feb 21 08:05:08 2024 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Mount count: 2 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Last checked: Wed Feb 21 08:00:42 2024 2024-02-21 15:17:41,308 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Lifetime writes: 5628 MB 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:17:41,309 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Directory Hash Seed: 15663457-3773-46ca-adc0-53e4e71155bb 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:17:41,309 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Checksum: 0x5f580c0c 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Journal features: journal_incompat_revoke journal_64bit journal_checksum_v3 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Total journal size: 512M 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Total journal blocks: 131072 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Max transaction length: 131072 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Journal sequence: 0x0000063c 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Journal start: 0 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:17:41,310 DEBUG probert.utils:50 Journal checksum: 0x4601c772 2024-02-21 15:17:41,311 DEBUG probert.utils:50 2024-02-21 15:17:41,311 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:17:41,311 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:17:41,311 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:17:41,324 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sdb4` exited with result: 0 2024-02-21 15:17:41,324 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:17:41,324 DEBUG probert.utils:50 Filesystem volume name: writable 2024-02-21 15:17:41,324 DEBUG probert.utils:50 Last mounted on: /root/var/crash 2024-02-21 15:17:41,324 DEBUG probert.utils:50 Filesystem UUID: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 2024-02-21 15:17:41,324 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Inode count: 1572864 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Block count: 6285056 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Reserved block count: 314252 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Overhead clusters: 142764 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Free blocks: 6142286 2024-02-21 15:17:41,325 DEBUG probert.utils:50 Free inodes: 1572853 2024-02-21 15:17:41,326 DEBUG probert.utils:50 First block: 0 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:17:41,326 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 15:15:59 2024 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Last mount time: Wed Feb 21 15:16:10 2024 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Last write time: Wed Feb 21 15:16:10 2024 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Mount count: 1 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Last checked: Wed Feb 21 15:15:59 2024 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Lifetime writes: 4170 kB 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:17:41,327 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:17:41,327 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Directory Hash Seed: 763e8651-8f61-441f-9c0c-14aaf8cfe53d 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Checksum: 0xb48eed66 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Journal features: journal_64bit journal_checksum_v3 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Total journal size: 128M 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Total journal blocks: 32768 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Max transaction length: 32768 2024-02-21 15:17:41,328 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:17:41,329 DEBUG probert.utils:50 Journal sequence: 0x00000002 2024-02-21 15:17:41,329 DEBUG probert.utils:50 Journal start: 1 2024-02-21 15:17:41,329 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:17:41,329 DEBUG probert.utils:50 Journal checksum: 0xc40c968a 2024-02-21 15:17:41,329 DEBUG probert.utils:50 2024-02-21 15:17:41,329 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:17:41,329 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:17:41,329 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:17:41,333 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sda2` exited with result: 0 2024-02-21 15:17:41,333 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:17:41,333 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 2238564 2024-02-21 15:17:41,333 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:17:41,333 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:17:41,333 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:17:41,336 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sdb4` exited with result: 0 2024-02-21 15:17:41,336 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:17:41,336 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 48986 2024-02-21 15:17:41,336 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:17:41,336 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:17:41,336 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:17:41,522 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None 2024-02-21 15:17:41,524 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 2024-02-21 15:17:41,524 DEBUG root:30 start: subiquity/SnapList/loader/fetch/kata-containers: 2024-02-21 15:17:41,727 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None 2024-02-21 15:17:41,729 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 2024-02-21 15:17:41,729 DEBUG root:30 start: subiquity/SnapList/loader/fetch/docker: 2024-02-21 15:17:41,961 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None 2024-02-21 15:17:41,963 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 2024-02-21 15:17:41,964 DEBUG root:30 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 2024-02-21 15:17:42,133 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 None 2024-02-21 15:17:42,135 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 2024-02-21 15:17:42,136 DEBUG root:30 start: subiquity/SnapList/loader/fetch/rocketchat-server: 2024-02-21 15:17:42,325 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None 2024-02-21 15:17:42,327 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 2024-02-21 15:17:42,327 DEBUG root:30 start: subiquity/SnapList/loader/fetch/mosquitto: 2024-02-21 15:17:42,364 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:17:42,364 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:17:42,365 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:17:42,366 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:17:42,367 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:17:42,368 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2024-02-21 15:17:42,368 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2024-02-21 15:17:42,371 DEBUG root:30 start: subiquity/Locale/GET: 2024-02-21 15:17:42,371 DEBUG root:30 finish: subiquity/Locale/GET: SUCCESS: 200 "C.UTF-8" 2024-02-21 15:17:42,469 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None 2024-02-21 15:17:42,471 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 2024-02-21 15:17:42,471 DEBUG root:30 start: subiquity/SnapList/loader/fetch/etcd: 2024-02-21 15:17:42,673 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None 2024-02-21 15:17:42,677 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 2024-02-21 15:17:42,677 DEBUG root:30 start: subiquity/SnapList/loader/fetch/powershell: 2024-02-21 15:17:42,869 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None 2024-02-21 15:17:42,871 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 2024-02-21 15:17:42,871 DEBUG root:30 start: subiquity/SnapList/loader/fetch/sabnzbd: 2024-02-21 15:17:43,032 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None 2024-02-21 15:17:43,034 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 2024-02-21 15:17:43,034 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wormhole: 2024-02-21 15:17:43,250 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None 2024-02-21 15:17:43,252 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 2024-02-21 15:17:43,252 DEBUG root:30 start: subiquity/SnapList/loader/fetch/aws-cli: 2024-02-21 15:17:43,475 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 None 2024-02-21 15:17:43,477 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 2024-02-21 15:17:43,477 DEBUG root:30 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 2024-02-21 15:17:43,680 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1835 2024-02-21 15:17:43,681 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 2024-02-21 15:17:43,682 DEBUG root:30 start: subiquity/SnapList/loader/fetch/slcli: 2024-02-21 15:17:43,851 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 None 2024-02-21 15:17:43,854 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 2024-02-21 15:17:43,855 DEBUG root:30 start: subiquity/SnapList/loader/fetch/doctl: 2024-02-21 15:17:44,059 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 None 2024-02-21 15:17:44,061 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 2024-02-21 15:17:44,061 DEBUG root:30 start: subiquity/SnapList/loader/fetch/conjure-up: 2024-02-21 15:17:44,259 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None 2024-02-21 15:17:44,260 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 2024-02-21 15:17:44,260 DEBUG root:30 start: subiquity/SnapList/loader/fetch/postgresql10: 2024-02-21 15:17:44,432 DEBUG curtin:1325 Extracting storage config from probe data 2024-02-21 15:17:44,432 DEBUG curtin:73 /dev/sda is multipath device member? False 2024-02-21 15:17:44,432 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:17:44,432 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:17:44,432 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:17:44,432 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:17:44,458 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:17:44,458 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:17:44,458 DEBUG curtin:61 /dev/sda1 is multipath device? False 2024-02-21 15:17:44,458 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:17:44,458 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:17:44,482 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:17:44,482 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:17:44,483 DEBUG curtin:61 /dev/sda2 is multipath device? False 2024-02-21 15:17:44,483 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:17:44,483 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:17:44,506 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1523 2024-02-21 15:17:44,506 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:17:44,507 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:17:44,507 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:17:44,507 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:17:44,507 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:17:44,530 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:17:44,531 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:17:44,531 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2024-02-21 15:17:44,531 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:17:44,531 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:17:44,554 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:17:44,554 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:17:44,554 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2024-02-21 15:17:44,555 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:17:44,555 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:17:44,577 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2024-02-21 15:17:44,578 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:17:44,578 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2024-02-21 15:17:44,578 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:17:44,578 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:17:44,601 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:17:44,601 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:17:44,601 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2024-02-21 15:17:44,601 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:17:44,601 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:17:44,624 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:17:44,647 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:17:44,669 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:17:44,692 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:17:44,714 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:17:44,738 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:17:44,784 DEBUG curtin:1332 Sorting extracted configurations 2024-02-21 15:17:44,784 INFO curtin:1351 Validating extracted storage config components 2024-02-21 15:17:44,828 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2024-02-21 15:17:44,828 DEBUG curtin:1372 Generating storage config dependencies 2024-02-21 15:17:44,828 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,828 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,829 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,830 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,831 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2024-02-21 15:17:44,831 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:17:44,831 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:17:44,831 DEBUG curtin:1378 Merging storage config dependencies 2024-02-21 15:17:44,842 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='33c5cbec-6558-40f4-96ec-ee84dbff8dc8', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='9a37e7c4-75de-48e4-8c1e-34e79ad665cf', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=5244174336, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='a929d47d-2f8f-4ff7-9522-971d2b318667', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5191680, flag='boot', number=2, preserve=True, offset=5244207104, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='a929d47d-2f8f-4ff7-9521-971d2b318667', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=5249398784, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='a929d47d-2f8f-4ff7-9520-971d2b318667', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=25743589376, flag='linux', number=4, preserve=True, offset=5251268608, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5', id='partition-sdb4', type='partition')} 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2024-02-21 15:17:44,843 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:17:44,844 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:26 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2024-02-21 15:17:44,845 DEBUG subiquity.models.filesystem:1475 considering mount of 0:63 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:49 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:78 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:17:44,846 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:17:44,847 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2024-02-21 15:17:44,847 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 2024-02-21 15:17:44,848 DEBUG subiquity.server.controllers.filesystem:1297 block probing took 4.8 seconds 2024-02-21 15:17:44,848 DEBUG subiquity.server.controllers.filesystem:1474 start_monitor 2024-02-21 15:17:44,849 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 2024-02-21 15:17:44,849 DEBUG root:30 start: subiquity/SnapList/loader/fetch/heroku: 2024-02-21 15:17:45,090 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None 2024-02-21 15:17:45,093 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 2024-02-21 15:17:45,093 DEBUG root:30 start: subiquity/SnapList/loader/fetch/keepalived: 2024-02-21 15:17:45,229 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None 2024-02-21 15:17:45,231 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 2024-02-21 15:17:45,232 DEBUG root:30 start: subiquity/SnapList/loader/fetch/prometheus: 2024-02-21 15:17:45,373 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None 2024-02-21 15:17:45,377 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 2024-02-21 15:17:45,378 DEBUG root:30 start: subiquity/SnapList/loader/fetch/juju: 2024-02-21 15:17:45,615 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=juju HTTP/1.1" 200 None 2024-02-21 15:17:45,619 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/juju: SUCCESS: 2024-02-21 15:17:45,620 DEBUG root:30 finish: subiquity/SnapList/loader: SUCCESS: 2024-02-21 15:17:48,958 DEBUG root:30 start: subiquity/Locale/POST: 2024-02-21 15:17:48,958 DEBUG subiquity.server.controllers.locale:68 en_US.UTF-8 2024-02-21 15:17:48,959 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'timezone', 'identity', 'drivers', 'network', 'codecs'} 2024-02-21 15:17:48,959 DEBUG root:30 finish: subiquity/Locale/POST: SUCCESS: 200 null 2024-02-21 15:17:48,959 DEBUG subiquitycore.utils:118 arun_command called: ['localectl', 'set-locale', 'en_US.UTF-8'] 2024-02-21 15:17:49,872 DEBUG subiquitycore.utils:132 arun_command ['localectl', 'set-locale', 'en_US.UTF-8'] exited with code 0 2024-02-21 15:17:54,321 DEBUG root:30 start: subiquity/Filesystem/has_rst_GET: 2024-02-21 15:17:54,323 DEBUG root:30 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false 2024-02-21 15:17:54,325 DEBUG root:30 start: subiquity/Keyboard/GET: 2024-02-21 15:17:54,350 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2024-02-21 15:17:54,355 DEBUG root:30 start: subiquity/Keyboard/GET: 2024-02-21 15:17:54,381 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2024-02-21 15:17:54,386 DEBUG root:30 start: subiquity/Keyboard/input_source_POST: 2024-02-21 15:17:54,387 DEBUG subiquitycore.utils:118 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] 2024-02-21 15:17:54,477 DEBUG subiquitycore.utils:132 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] exited with code 0 2024-02-21 15:17:54,478 DEBUG root:30 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2024-02-21 15:18:00,842 DEBUG root:30 start: subiquity/Keyboard/input_source_POST: 2024-02-21 15:18:00,843 DEBUG subiquitycore.utils:118 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','is')]"] 2024-02-21 15:18:00,905 DEBUG subiquitycore.utils:132 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','is')]"] exited with code 0 2024-02-21 15:18:00,906 DEBUG root:30 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2024-02-21 15:18:02,436 DEBUG root:30 start: subiquity/Keyboard/POST: 2024-02-21 15:18:02,437 DEBUG subiquity.server.controllers.keyboard:221 KeyboardSetting(layout='is', variant='', toggle=None) 2024-02-21 15:18:02,438 DEBUG subiquitycore.utils:118 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only'] 2024-02-21 15:18:03,777 DEBUG subiquitycore.utils:132 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0 2024-02-21 15:18:03,777 DEBUG subiquitycore.utils:118 arun_command called: ['/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin/subiquity-loadkeys'] 2024-02-21 15:18:03,782 DEBUG subiquitycore.utils:132 arun_command ['/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin/subiquity-loadkeys'] exited with code 0 2024-02-21 15:18:03,783 DEBUG subiquity.models.subiquity:256 model keyboard for install stage is configured, to go {'filesystem', 'source', 'network'} 2024-02-21 15:18:03,783 DEBUG root:30 finish: subiquity/Keyboard/POST: SUCCESS: 200 null 2024-02-21 15:18:03,814 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:03,843 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:03,843 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2024-02-21 15:18:05,083 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:05,103 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:05,103 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2024-02-21 15:18:05,104 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:05,135 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:05,171 DEBUG root:30 start: subiquity/Refresh/GET: 2024-02-21 15:18:05,171 DEBUG root:30 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "0+git.15cde5fb", "ne... 2024-02-21 15:18:05,179 DEBUG root:30 start: subiquity/Source/GET: 2024-02-21 15:18:05,180 DEBUG root:30 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal... 2024-02-21 15:18:05,183 DEBUG root:30 start: subiquity/Drivers/GET: 2024-02-21 15:18:05,184 DEBUG root:30 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": true, "search_drivers": false} 2024-02-21 15:18:05,185 DEBUG root:30 start: subiquity/Codecs/GET: 2024-02-21 15:18:05,185 DEBUG root:30 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false} 2024-02-21 15:18:05,980 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:05,991 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:05,991 DEBUG probert.network:741 wlan_event {'cmd': 'TRIGGER_SCAN', 'ifindex': 3} 2024-02-21 15:18:06,700 DEBUG root:30 start: subiquity/Source/POST: 2024-02-21 15:18:06,701 DEBUG subiquity.models.subiquity:256 model source for install stage is configured, to go {'filesystem', 'network'} 2024-02-21 15:18:06,701 DEBUG subiquity.server.controllers.drivers:89 source variant has been set. Querying list of drivers. 2024-02-21 15:18:06,702 DEBUG root:30 finish: subiquity/Source/POST: SUCCESS: 200 null 2024-02-21 15:18:06,702 DEBUG root:30 start: subiquity/Drivers/POST: 2024-02-21 15:18:06,704 DEBUG subiquity.models.subiquity:256 model drivers for postinstall stage is configured, to go {'timezone', 'identity', 'network', 'codecs'} 2024-02-21 15:18:06,704 DEBUG root:30 finish: subiquity/Drivers/POST: SUCCESS: 200 null 2024-02-21 15:18:06,705 DEBUG root:30 start: subiquity/Codecs/POST: 2024-02-21 15:18:06,705 DEBUG subiquity.models.subiquity:256 model codecs for postinstall stage is configured, to go {'timezone', 'identity', 'network'} 2024-02-21 15:18:06,705 DEBUG root:30 finish: subiquity/Codecs/POST: SUCCESS: 200 null 2024-02-21 15:18:06,706 DEBUG subiquity.server.controllers.filesystem:414 got system None for variation minimal 2024-02-21 15:18:06,706 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.en.squashfs'] 2024-02-21 15:18:06,707 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp1i8ws7fm/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:06,720 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:06,739 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.en.squashfs', '/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.en.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:06,831 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.en.squashfs.dir:/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.squashfs.dir:/tmp/tmp1i8ws7fm/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp1i8ws7fm/root.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:06,845 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'mount', '-o', 'bind', '/tmp/tmp1i8ws7fm/root.dir/var/lib/snapd/seed/systems', '/var/lib/snapd/seed/systems'] 2024-02-21 15:18:06,855 DEBUG root:30 start: subiquity/Drivers/_list_drivers: 2024-02-21 15:18:06,855 DEBUG root:30 start: subiquity/Drivers/_list_drivers/wait_apt: 2024-02-21 15:18:06,856 DEBUG subiquity.server.controllers.filesystem:1487 stop_monitor 2024-02-21 15:18:06,856 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:06,879 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:06,880 DEBUG subiquity.server.controllers.filesystem:1499 Triggered Probert run on udev event 2024-02-21 15:18:06,881 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2024-02-21 15:18:06,882 DEBUG root:30 start: subiquity/Filesystem/_probe: 2024-02-21 15:18:06,883 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2024-02-21 15:18:06,885 DEBUG asyncio:54 Using selector: EpollSelector 2024-02-21 15:18:06,904 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-02-21 15:18:06,906 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-02-21 15:18:06,930 DEBUG probert.dasd:134 Probing DASD devies 2024-02-21 15:18:06,930 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2024-02-21 15:18:06,953 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'mount', '-o', 'bind', '/tmp/tmp1i8ws7fm/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1588.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1588.snap'] 2024-02-21 15:18:07,013 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'mount', '-o', 'bind', '/tmp/tmp1i8ws7fm/root.dir/var/lib/snapd/seed/snaps/pc_161.snap', '/var/lib/snapd/seed/snaps/pc_161.snap'] 2024-02-21 15:18:07,047 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:07,067 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:07,067 DEBUG probert.network:741 wlan_event {'cmd': 'NEW_SCAN_RESULTS', 'ifindex': 3, 'ssids': [(b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'Hringdu-7WWf', 'no status'), (b'NOVA-K6LAQ9', 'no status'), (b'Hringdu-7WWf', 'no status')]} 2024-02-21 15:18:07,067 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:07,087 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:07,949 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sda2` exited with result: 0 2024-02-21 15:18:07,949 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:07,949 DEBUG probert.utils:50 Filesystem volume name: 2024-02-21 15:18:07,949 DEBUG probert.utils:50 Last mounted on: / 2024-02-21 15:18:07,949 DEBUG probert.utils:50 Filesystem UUID: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 2024-02-21 15:18:07,949 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Inode count: 7749632 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Block count: 30982912 2024-02-21 15:18:07,950 DEBUG probert.utils:50 Reserved block count: 1549145 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Overhead clusters: 632916 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Free blocks: 28503633 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Free inodes: 7665667 2024-02-21 15:18:07,951 DEBUG probert.utils:50 First block: 0 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:18:07,951 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 08:00:42 2024 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Last mount time: Wed Feb 21 08:05:08 2024 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Last write time: Wed Feb 21 08:05:08 2024 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Mount count: 2 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Last checked: Wed Feb 21 08:00:42 2024 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Lifetime writes: 5628 MB 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:18:07,952 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:18:07,952 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Directory Hash Seed: 15663457-3773-46ca-adc0-53e4e71155bb 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Checksum: 0x5f580c0c 2024-02-21 15:18:07,953 DEBUG probert.utils:50 Journal features: journal_incompat_revoke journal_64bit journal_checksum_v3 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Total journal size: 512M 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Total journal blocks: 131072 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Max transaction length: 131072 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Journal sequence: 0x0000063c 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Journal start: 0 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:18:07,954 DEBUG probert.utils:50 Journal checksum: 0x4601c772 2024-02-21 15:18:07,954 DEBUG probert.utils:50 2024-02-21 15:18:07,954 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:07,954 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:07,954 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:07,960 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sdb4` exited with result: 0 2024-02-21 15:18:07,960 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem volume name: writable 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Last mounted on: /root/var/crash 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem UUID: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:18:07,961 DEBUG probert.utils:50 Inode count: 1572864 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Block count: 6285056 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Reserved block count: 314252 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Overhead clusters: 142764 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Free blocks: 6142286 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Free inodes: 1572853 2024-02-21 15:18:07,962 DEBUG probert.utils:50 First block: 0 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:18:07,962 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 15:15:59 2024 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Last mount time: Wed Feb 21 15:16:10 2024 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Last write time: Wed Feb 21 15:16:10 2024 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Mount count: 1 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Last checked: Wed Feb 21 15:15:59 2024 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:18:07,963 DEBUG probert.utils:50 Lifetime writes: 4170 kB 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:18:07,964 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Directory Hash Seed: 763e8651-8f61-441f-9c0c-14aaf8cfe53d 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Checksum: 0xb48eed66 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Journal features: journal_64bit journal_checksum_v3 2024-02-21 15:18:07,964 DEBUG probert.utils:50 Total journal size: 128M 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Total journal blocks: 32768 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Max transaction length: 32768 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Journal sequence: 0x00000002 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Journal start: 1 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:18:07,965 DEBUG probert.utils:50 Journal checksum: 0xc40c968a 2024-02-21 15:18:07,965 DEBUG probert.utils:50 2024-02-21 15:18:07,965 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:07,965 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:07,965 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:07,970 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sda2` exited with result: 0 2024-02-21 15:18:07,970 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:07,971 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 2238564 2024-02-21 15:18:07,971 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:07,971 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:07,971 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:07,976 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sdb4` exited with result: 0 2024-02-21 15:18:07,976 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:07,976 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 49045 2024-02-21 15:18:07,976 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:07,976 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:07,976 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:07,987 DEBUG curtin:1325 Extracting storage config from probe data 2024-02-21 15:18:07,987 DEBUG curtin:73 /dev/sda is multipath device member? False 2024-02-21 15:18:07,987 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:07,987 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:07,988 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:07,988 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:08,016 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:08,016 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:08,016 DEBUG curtin:61 /dev/sda1 is multipath device? False 2024-02-21 15:18:08,016 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:08,016 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:08,042 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:08,043 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:08,043 DEBUG curtin:61 /dev/sda2 is multipath device? False 2024-02-21 15:18:08,043 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:08,043 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:08,070 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:08,070 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:08,070 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:08,071 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:08,071 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:08,097 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:08,097 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:08,097 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2024-02-21 15:18:08,097 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:08,097 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:08,125 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:08,125 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:08,125 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2024-02-21 15:18:08,125 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:08,125 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:08,151 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2024-02-21 15:18:08,152 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:08,152 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2024-02-21 15:18:08,152 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:08,152 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:08,177 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:08,178 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:08,178 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2024-02-21 15:18:08,178 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:08,178 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:08,204 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:08,230 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:08,255 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:08,281 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:08,306 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:08,332 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:08,384 DEBUG curtin:1332 Sorting extracted configurations 2024-02-21 15:18:08,384 INFO curtin:1351 Validating extracted storage config components 2024-02-21 15:18:08,438 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2024-02-21 15:18:08,438 DEBUG curtin:1372 Generating storage config dependencies 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,439 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,440 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:08,441 DEBUG curtin:1378 Merging storage config dependencies 2024-02-21 15:18:08,453 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2024-02-21 15:18:08,454 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='33c5cbec-6558-40f4-96ec-ee84dbff8dc8', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='9a37e7c4-75de-48e4-8c1e-34e79ad665cf', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=5244174336, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='a929d47d-2f8f-4ff7-9522-971d2b318667', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5191680, flag='boot', number=2, preserve=True, offset=5244207104, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='a929d47d-2f8f-4ff7-9521-971d2b318667', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=5249398784, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='a929d47d-2f8f-4ff7-9520-971d2b318667', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=25743589376, flag='linux', number=4, preserve=True, offset=5251268608, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5', id='partition-sdb4', type='partition')} 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:08,455 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:74 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:74 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:74 2024-02-21 15:18:08,456 DEBUG subiquity.models.filesystem:1475 considering mount of 0:26 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2024-02-21 15:18:08,457 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 0:63 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:08,458 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:13 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 7:14 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:74 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:49 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:78 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:08,459 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:08,460 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:08,460 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:08,460 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2024-02-21 15:18:08,460 DEBUG subiquity.server.controllers.filesystem:1297 block probing took 1.6 seconds 2024-02-21 15:18:08,460 DEBUG subiquity.server.controllers.filesystem:1474 start_monitor 2024-02-21 15:18:08,461 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 2024-02-21 15:18:10,534 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None 2024-02-21 15:18:10,537 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_161.snap'] 2024-02-21 15:18:10,615 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1588.snap'] 2024-02-21 15:18:10,673 DEBUG subiquitycore.utils:151 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3716', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/6/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/sbin:/snap/ubuntu-desktop-bootstrap/6/usr/bin:/snap/ubuntu-desktop-bootstrap/6/sbin:/snap/ubuntu-desktop-bootstrap/6/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', '--setenv', 'PYTHONPATH=/snap/ubuntu-desktop-bootstrap/6/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/6/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/6', '--', 'umount', '/var/lib/snapd/seed/systems'] 2024-02-21 15:18:10,724 DEBUG curtin:95 Running command ['umount', '/tmp/tmp1i8ws7fm/root.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:10,735 DEBUG curtin:95 Running command ['umount', '/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.en.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:10,743 DEBUG curtin:95 Running command ['umount', '/tmp/tmp1i8ws7fm/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:10,783 DEBUG curtin:95 Running command ['umount', '/tmp/tmp1i8ws7fm/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2024-02-21 15:18:10,791 DEBUG subiquity.server.controllers.filesystem:344 got system SystemDetails(current=False, volumes={'pc': Volume(schema='gpt', bootloader='grub', id='', structure=[VolumeStructure(name='EFI System partition', label='ubuntu-seed', offset=1048576, offset_write=None, size=786432000, type='C12A7328-F81F-11D2-BA4B-00A0C93EC93B', role='system-seed-null', id='', filesystem='vfat', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=3, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=787480576, offset_write=None, size=786432000, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-boot', id='', filesystem='ext4', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=2, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=1573912576, offset_write=None, size=33554432, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-save', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None)), VolumeStructure(name='ubuntu-data', label='ubuntu-data', offset=1607467008, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='not encrypting device storage as checking TPM gave: the TPM is in DA lockout mode')) 2024-02-21 15:18:10,793 DEBUG subiquity.server.controllers.filesystem:414 got system SystemDetails(current=False, volumes={'pc': Volume(schema='gpt', bootloader='grub', id='', structure=[VolumeStructure(name='EFI System partition', label='ubuntu-seed', offset=1048576, offset_write=None, size=786432000, type='C12A7328-F81F-11D2-BA4B-00A0C93EC93B', role='system-seed-null', id='', filesystem='vfat', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=3, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=787480576, offset_write=None, size=786432000, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-boot', id='', filesystem='ext4', content=[VolumeContent(source='grubx64.efi', target='EFI/boot/grubx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=2, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=1573912576, offset_write=None, size=33554432, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-save', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None)), VolumeStructure(name='ubuntu-data', label='ubuntu-data', offset=1607467008, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='not encrypting device storage as checking TPM gave: the TPM is in DA lockout mode')) for variation minimal-enhanced-secureboot 2024-02-21 15:18:10,793 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:10,804 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:10,807 DEBUG subiquity.server.controllers.filesystem:1487 stop_monitor 2024-02-21 15:18:10,807 DEBUG subiquitycore.utils:76 run_command called: ['udevadm', 'settle', '-t', '0'] 2024-02-21 15:18:10,839 DEBUG subiquitycore.utils:95 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2024-02-21 15:18:10,839 DEBUG subiquity.server.controllers.filesystem:1499 Triggered Probert run on udev event 2024-02-21 15:18:10,839 DEBUG root:30 start: subiquity/Filesystem/_probe: 2024-02-21 15:18:10,840 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2024-02-21 15:18:10,841 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2024-02-21 15:18:10,842 DEBUG asyncio:54 Using selector: EpollSelector 2024-02-21 15:18:10,859 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-02-21 15:18:10,861 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-02-21 15:18:10,875 DEBUG probert.dasd:134 Probing DASD devies 2024-02-21 15:18:10,875 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2024-02-21 15:18:11,552 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sda2` exited with result: 0 2024-02-21 15:18:11,552 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem volume name: 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Last mounted on: / 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem UUID: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:18:11,552 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Inode count: 7749632 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Block count: 30982912 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Reserved block count: 1549145 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Overhead clusters: 632916 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Free blocks: 28503633 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Free inodes: 7665667 2024-02-21 15:18:11,553 DEBUG probert.utils:50 First block: 0 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:18:11,553 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 08:00:42 2024 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Last mount time: Wed Feb 21 08:05:08 2024 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Last write time: Wed Feb 21 08:05:08 2024 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Mount count: 2 2024-02-21 15:18:11,554 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Last checked: Wed Feb 21 08:00:42 2024 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Lifetime writes: 5628 MB 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:18:11,555 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Directory Hash Seed: 15663457-3773-46ca-adc0-53e4e71155bb 2024-02-21 15:18:11,555 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Checksum: 0x5f580c0c 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Journal features: journal_incompat_revoke journal_64bit journal_checksum_v3 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Total journal size: 512M 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Total journal blocks: 131072 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Max transaction length: 131072 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Journal sequence: 0x0000063c 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Journal start: 0 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:18:11,556 DEBUG probert.utils:50 Journal checksum: 0x4601c772 2024-02-21 15:18:11,556 DEBUG probert.utils:50 2024-02-21 15:18:11,556 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:11,556 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:11,557 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:11,560 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sdb4` exited with result: 0 2024-02-21 15:18:11,560 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Filesystem volume name: writable 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Last mounted on: /root/var/crash 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Filesystem UUID: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Filesystem magic number: 0xEF53 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Filesystem revision #: 1 (dynamic) 2024-02-21 15:18:11,560 DEBUG probert.utils:50 Filesystem features: has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Filesystem flags: signed_directory_hash 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Default mount options: user_xattr acl 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Filesystem state: clean 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Errors behavior: Continue 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Filesystem OS type: Linux 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Inode count: 1572864 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Block count: 6285056 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Reserved block count: 314252 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Overhead clusters: 142764 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Free blocks: 6142286 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Free inodes: 1572853 2024-02-21 15:18:11,561 DEBUG probert.utils:50 First block: 0 2024-02-21 15:18:11,561 DEBUG probert.utils:50 Block size: 4096 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Fragment size: 4096 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Group descriptor size: 64 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Reserved GDT blocks: 1024 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Blocks per group: 32768 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Fragments per group: 32768 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Inodes per group: 8192 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Inode blocks per group: 512 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Flex block group size: 16 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Filesystem created: Wed Feb 21 15:15:59 2024 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Last mount time: Wed Feb 21 15:16:10 2024 2024-02-21 15:18:11,562 DEBUG probert.utils:50 Last write time: Wed Feb 21 15:16:10 2024 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Mount count: 1 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Maximum mount count: -1 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Last checked: Wed Feb 21 15:15:59 2024 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Check interval: 0 () 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Lifetime writes: 4170 kB 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Reserved blocks uid: 0 (user root) 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Reserved blocks gid: 0 (group root) 2024-02-21 15:18:11,563 DEBUG probert.utils:50 First inode: 11 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Inode size: 256 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Required extra isize: 32 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Desired extra isize: 32 2024-02-21 15:18:11,563 DEBUG probert.utils:50 Journal inode: 8 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Default directory hash: half_md4 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Directory Hash Seed: 763e8651-8f61-441f-9c0c-14aaf8cfe53d 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Journal backup: inode blocks 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Checksum type: crc32c 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Checksum: 0xb48eed66 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Journal features: journal_64bit journal_checksum_v3 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Total journal size: 128M 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Total journal blocks: 32768 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Max transaction length: 32768 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Fast commit length: 0 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Journal sequence: 0x00000002 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Journal start: 1 2024-02-21 15:18:11,564 DEBUG probert.utils:50 Journal checksum type: crc32c 2024-02-21 15:18:11,565 DEBUG probert.utils:50 Journal checksum: 0xc40c968a 2024-02-21 15:18:11,565 DEBUG probert.utils:50 2024-02-21 15:18:11,565 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:11,565 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:11,565 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:11,568 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sda2` exited with result: 0 2024-02-21 15:18:11,568 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:11,568 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 2238564 2024-02-21 15:18:11,568 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:11,568 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:11,568 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:11,575 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sdb4` exited with result: 0 2024-02-21 15:18:11,575 DEBUG probert.utils:48 stdout: ------------------------------------------ 2024-02-21 15:18:11,575 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 49046 2024-02-21 15:18:11,575 DEBUG probert.utils:48 stderr: ------------------------------------------ 2024-02-21 15:18:11,575 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023) 2024-02-21 15:18:11,575 DEBUG probert.utils:88 -------------------------------------------------- 2024-02-21 15:18:11,581 DEBUG curtin:1325 Extracting storage config from probe data 2024-02-21 15:18:11,581 DEBUG curtin:73 /dev/sda is multipath device member? False 2024-02-21 15:18:11,581 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:11,581 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:11,581 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:11,581 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:11,608 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:11,608 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:11,608 DEBUG curtin:61 /dev/sda1 is multipath device? False 2024-02-21 15:18:11,608 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:11,608 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:11,634 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:11,634 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:11,634 DEBUG curtin:61 /dev/sda2 is multipath device? False 2024-02-21 15:18:11,634 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:11,634 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:11,659 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:11,660 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:11,660 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:11,660 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:11,660 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:11,685 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:11,686 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:11,686 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2024-02-21 15:18:11,686 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:11,686 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:11,711 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:11,715 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:11,716 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2024-02-21 15:18:11,716 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:11,716 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:11,742 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2024-02-21 15:18:11,742 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:11,743 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2024-02-21 15:18:11,743 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:11,743 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:11,767 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:11,768 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:11,768 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2024-02-21 15:18:11,768 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:11,768 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:11,793 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:11,818 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:11,842 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:11,865 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:11,889 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:11,913 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:11,962 DEBUG curtin:1332 Sorting extracted configurations 2024-02-21 15:18:11,962 INFO curtin:1351 Validating extracted storage config components 2024-02-21 15:18:12,008 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2024-02-21 15:18:12,009 DEBUG curtin:1372 Generating storage config dependencies 2024-02-21 15:18:12,009 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,009 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,009 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,009 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,009 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,010 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:12,011 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:12,012 DEBUG curtin:1378 Merging storage config dependencies 2024-02-21 15:18:12,023 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='33c5cbec-6558-40f4-96ec-ee84dbff8dc8', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='9a37e7c4-75de-48e4-8c1e-34e79ad665cf', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=5244174336, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='a929d47d-2f8f-4ff7-9522-971d2b318667', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5191680, flag='boot', number=2, preserve=True, offset=5244207104, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='a929d47d-2f8f-4ff7-9521-971d2b318667', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=5249398784, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='a929d47d-2f8f-4ff7-9520-971d2b318667', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=25743589376, flag='linux', number=4, preserve=True, offset=5251268608, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5', id='partition-sdb4', type='partition')} 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:12,024 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:12,025 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:26 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2024-02-21 15:18:12,026 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:63 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:49 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2024-02-21 15:18:12,027 DEBUG subiquity.models.filesystem:1475 considering mount of 0:78 2024-02-21 15:18:12,028 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:12,028 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:12,028 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:12,028 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:12,028 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2024-02-21 15:18:12,028 DEBUG subiquity.server.controllers.filesystem:1297 block probing took 1.2 seconds 2024-02-21 15:18:12,028 DEBUG subiquity.server.controllers.filesystem:1474 start_monitor 2024-02-21 15:18:12,029 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 2024-02-21 15:18:12,029 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:12,035 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:12,037 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 2024-02-21 15:18:12,038 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:12,041 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2024-02-21 15:18:12,044 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 2024-02-21 15:18:12,044 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2024-02-21 15:18:15,284 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 2024-02-21 15:18:15,284 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2024-02-21 15:18:15,286 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2024-02-21 15:18:15,287 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:15,293 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:15,296 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 2024-02-21 15:18:15,297 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:15,301 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2024-02-21 15:18:15,304 DEBUG root:30 start: subiquity/Identity/GET: 2024-02-21 15:18:15,304 DEBUG root:30 finish: subiquity/Identity/GET: SUCCESS: 200 {"realname": "", "username": "", "crypted_password": "", "hostname": ""} 2024-02-21 15:18:15,307 DEBUG root:30 start: subiquity/Ad/has_support_GET: 2024-02-21 15:18:15,308 DEBUG root:30 finish: subiquity/Ad/has_support_GET: SUCCESS: 200 true 2024-02-21 15:18:18,214 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 2024-02-21 15:18:18,216 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK" 2024-02-21 15:18:19,053 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 2024-02-21 15:18:19,054 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK" 2024-02-21 15:18:24,288 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2024-02-21 15:18:24,288 DEBUG subiquity.models.subiquity:256 model active_directory for postinstall stage is configured, to go {'timezone', 'identity', 'network'} 2024-02-21 15:18:24,289 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-02-21 15:18:24,370 DEBUG root:30 start: subiquity/Identity/POST: 2024-02-21 15:18:24,371 DEBUG subiquity.models.subiquity:256 model identity for postinstall stage is configured, to go {'timezone', 'network'} 2024-02-21 15:18:24,371 DEBUG root:30 finish: subiquity/Identity/POST: SUCCESS: 200 null 2024-02-21 15:18:24,374 DEBUG root:30 start: subiquity/TimeZone/GET: 2024-02-21 15:18:24,374 DEBUG root:30 finish: subiquity/TimeZone/GET: SUCCESS: 200 {"timezone": "Atlantic/Reykjavik", "from_geoip": true} 2024-02-21 15:18:26,032 DEBUG root:30 start: subiquity/TimeZone/POST: 2024-02-21 15:18:26,331 DEBUG subiquity.models.subiquity:256 model timezone for postinstall stage is configured, to go {'network'} 2024-02-21 15:18:26,331 DEBUG root:30 finish: subiquity/TimeZone/POST: SUCCESS: 200 null 2024-02-21 15:18:26,336 DEBUG root:30 start: subiquity/Filesystem/v2_guided_POST: 2024-02-21 15:18:26,337 DEBUG subiquity.server.controllers.filesystem:1152 GuidedChoiceV2(target=GuidedStorageTargetReformat(disk_id='disk-sda', allowed=[, , , , ], disallowed=[GuidedDisallowedCapability(capability=, reason=, message='not encrypting device storage as checking TPM gave: the TPM is in DA lockout mode')]), capability=, recovery_key=None, sizing_policy=, reset_partition=False) 2024-02-21 15:18:26,338 DEBUG subiquity.models.filesystem:2105 adding fat32 to Partition(device=disk-sda, size=1127219200, wipe='superblock', flag='boot', number=1, grub_device=True, offset=1048576, id='partition-0') 2024-02-21 15:18:26,339 DEBUG subiquity.models.filesystem:2105 adding ext4 to Partition(device=disk-sda, size=126906007552, wipe='superblock', number=2, grub_device=None, offset=1128267776, id='partition-1') 2024-02-21 15:18:26,339 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:26,343 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": {"target": {"disk_id":... 2024-02-21 15:18:26,348 DEBUG root:30 start: subiquity/Filesystem/v2_POST: 2024-02-21 15:18:26,349 DEBUG subiquity.models.subiquity:256 model filesystem for install stage is configured, to go {'network'} 2024-02-21 15:18:26,349 DEBUG subiquity.server.controllers.filesystem:1487 stop_monitor 2024-02-21 15:18:26,350 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:26,356 DEBUG root:30 finish: subiquity/Filesystem/v2_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:26,360 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 2024-02-21 15:18:26,360 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:26,366 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:26,368 DEBUG root:30 start: subiquity/Filesystem/v2_orig_config_GET: 2024-02-21 15:18:26,369 DEBUG curtin:1325 Extracting storage config from probe data 2024-02-21 15:18:26,369 DEBUG curtin:73 /dev/sda is multipath device member? False 2024-02-21 15:18:26,369 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:26,369 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:26,369 DEBUG curtin:86 /dev/sda is multipath device partition? False 2024-02-21 15:18:26,369 DEBUG curtin:61 /dev/sda is multipath device? False 2024-02-21 15:18:26,394 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:26,394 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:26,394 DEBUG curtin:61 /dev/sda1 is multipath device? False 2024-02-21 15:18:26,394 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:26,394 DEBUG curtin:86 /dev/sda1 is multipath device partition? False 2024-02-21 15:18:26,419 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:26,420 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:26,420 DEBUG curtin:61 /dev/sda2 is multipath device? False 2024-02-21 15:18:26,420 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:26,420 DEBUG curtin:86 /dev/sda2 is multipath device partition? False 2024-02-21 15:18:26,446 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:26,446 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:26,446 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:26,446 DEBUG curtin:86 /dev/sdb is multipath device partition? False 2024-02-21 15:18:26,446 DEBUG curtin:61 /dev/sdb is multipath device? False 2024-02-21 15:18:26,472 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:26,472 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:26,472 DEBUG curtin:61 /dev/sdb1 is multipath device? False 2024-02-21 15:18:26,472 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:26,472 DEBUG curtin:86 /dev/sdb1 is multipath device partition? False 2024-02-21 15:18:26,497 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:26,497 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:26,498 DEBUG curtin:61 /dev/sdb2 is multipath device? False 2024-02-21 15:18:26,498 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:26,498 DEBUG curtin:86 /dev/sdb2 is multipath device partition? False 2024-02-21 15:18:26,522 DEBUG curtin:73 /dev/sdb3 is multipath device member? False 2024-02-21 15:18:26,523 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:26,523 DEBUG curtin:61 /dev/sdb3 is multipath device? False 2024-02-21 15:18:26,523 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:26,523 DEBUG curtin:86 /dev/sdb3 is multipath device partition? False 2024-02-21 15:18:26,549 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:26,549 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:26,549 DEBUG curtin:61 /dev/sdb4 is multipath device? False 2024-02-21 15:18:26,549 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:26,549 DEBUG curtin:86 /dev/sdb4 is multipath device partition? False 2024-02-21 15:18:26,574 DEBUG curtin:73 /dev/sda1 is multipath device member? False 2024-02-21 15:18:26,599 DEBUG curtin:73 /dev/sdb is multipath device member? False 2024-02-21 15:18:26,623 DEBUG curtin:73 /dev/sdb1 is multipath device member? False 2024-02-21 15:18:26,649 DEBUG curtin:73 /dev/sdb2 is multipath device member? False 2024-02-21 15:18:26,673 DEBUG curtin:73 /dev/sda2 is multipath device member? False 2024-02-21 15:18:26,698 DEBUG curtin:73 /dev/sdb4 is multipath device member? False 2024-02-21 15:18:26,749 DEBUG curtin:1332 Sorting extracted configurations 2024-02-21 15:18:26,749 INFO curtin:1351 Validating extracted storage config components 2024-02-21 15:18:26,796 DEBUG curtin:1368 Extracted (unmerged) storage config: storage: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2024-02-21 15:18:26,796 DEBUG curtin:1372 Generating storage config dependencies 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sdb3:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,797 DEBUG curtin:238 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: format-disk-sdb:SourceType:format -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: format-partition-sdb2:SourceType:format -> (DepId:partition-sdb2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: partition-sdb2:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,798 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:238 Validate: format-partition-sdb4:SourceType:format -> (DepId:partition-sdb4 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:238 Validate: partition-sdb4:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:238 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:238 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'dm_crypt', 'raid', 'bcache', 'disk', 'partition', 'lvm_partition'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:238 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'disk', 'partition', 'raid', 'bcache'} ? result=True 2024-02-21 15:18:26,799 DEBUG curtin:1378 Merging storage config dependencies 2024-02-21 15:18:26,810 DEBUG curtin:1383 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: FORESEE_128GB_SSD_K44700J003729 type: disk wwn: '0x502b2a201d1c1b1a' - id: disk-sdb path: /dev/sdb ptable: gpt serial: Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0 type: disk - fstype: iso9660 id: format-disk-sdb type: format volume: disk-sdb - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sda1 size: 1127219200 type: partition uuid: 33c5cbec-6558-40f4-96ec-ee84dbff8dc8 - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 1128267776 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sda2 size: 126906007552 type: partition uuid: 9a37e7c4-75de-48e4-8c1e-34e79ad665cf - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: 7e309161-7a21-42f3-91a1-e6bfe6bafed7 volume: partition-sda2 - device: disk-sdb id: partition-sdb1 number: 1 offset: 32768 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb1 size: 5244174336 type: partition uuid: a929d47d-2f8f-4ff7-9522-971d2b318667 - device: disk-sdb flag: boot id: partition-sdb2 number: 2 offset: 5244207104 partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b path: /dev/sdb2 size: 5191680 type: partition uuid: a929d47d-2f8f-4ff7-9521-971d2b318667 - device: disk-sdb id: partition-sdb3 number: 3 offset: 5249398784 partition_type: ebd0a0a2-b9e5-4433-87c0-68b6b72699c7 path: /dev/sdb3 size: 307200 type: partition uuid: a929d47d-2f8f-4ff7-9520-971d2b318667 - device: disk-sdb flag: linux id: partition-sdb4 number: 4 offset: 5251268608 partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4 path: /dev/sdb4 size: 25743589376 type: partition uuid: b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5 - fstype: iso9660 id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sdb2 type: format volume: partition-sdb2 - fstype: ext4 id: format-partition-sdb4 type: format uuid: cbbdcef4-3709-4fa4-b9e6-813f6d46b979 volume: partition-sdb4 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount version: 2 2024-02-21 15:18:26,811 DEBUG subiquity.models.filesystem:1463 majmin_to_dev {'8:0': Disk(ptable='gpt', serial='FORESEE_128GB_SSD_K44700J003729', wwn='0x502b2a201d1c1b1a', path='/dev/sda', preserve=True, id='disk-sda', type='disk'), '8:16': Disk(ptable='gpt', serial='Kingston_DataTraveler_3.0_40B0767E21E6157106584887-0:0', path='/dev/sdb', preserve=True, id='disk-sdb', type='disk'), '8:1': Partition(device=disk-sda, size=1127219200, flag='boot', number=1, preserve=True, offset=1048576, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sda1', uuid='33c5cbec-6558-40f4-96ec-ee84dbff8dc8', id='partition-sda1', type='partition'), '8:2': Partition(device=disk-sda, size=126906007552, flag='linux', number=2, preserve=True, offset=1128267776, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sda2', uuid='9a37e7c4-75de-48e4-8c1e-34e79ad665cf', id='partition-sda2', type='partition'), '8:17': Partition(device=disk-sdb, size=5244174336, number=1, preserve=True, offset=32768, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb1', uuid='a929d47d-2f8f-4ff7-9522-971d2b318667', id='partition-sdb1', type='partition'), '8:18': Partition(device=disk-sdb, size=5191680, flag='boot', number=2, preserve=True, offset=5244207104, partition_type='c12a7328-f81f-11d2-ba4b-00a0c93ec93b', path='/dev/sdb2', uuid='a929d47d-2f8f-4ff7-9521-971d2b318667', id='partition-sdb2', type='partition'), '8:19': Partition(device=disk-sdb, size=307200, number=3, preserve=True, offset=5249398784, partition_type='ebd0a0a2-b9e5-4433-87c0-68b6b72699c7', path='/dev/sdb3', uuid='a929d47d-2f8f-4ff7-9520-971d2b318667', id='partition-sdb3', type='partition'), '8:20': Partition(device=disk-sdb, size=25743589376, flag='linux', number=4, preserve=True, offset=5251268608, partition_type='0fc63daf-8483-4772-8e79-3d69d8477de4', path='/dev/sdb4', uuid='b076ec3d-6bbd-4e97-9adc-0b3dfa2ea3b5', id='partition-sdb4', type='partition')} 2024-02-21 15:18:26,811 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:22 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:23 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:5 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 8:17 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1480 /dev/sdb1 is mounted 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 7:0 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 8:20 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1480 /dev/sdb4 is mounted 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:26,812 DEBUG subiquity.models.filesystem:1475 considering mount of 0:42 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:29 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:26 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:6 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:36 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:37 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:38 2024-02-21 15:18:26,813 DEBUG subiquity.models.filesystem:1475 considering mount of 0:12 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:7 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:21 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:41 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:39 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:24 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:34 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:40 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:20 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:35 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:63 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 0:25 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 7:3 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 7:7 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 7:6 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 7:4 2024-02-21 15:18:26,814 DEBUG subiquity.models.filesystem:1475 considering mount of 7:5 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 7:8 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 7:10 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 7:11 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 7:9 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 7:12 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:49 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:72 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:78 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:26,815 DEBUG subiquity.models.filesystem:1475 considering mount of 0:4 2024-02-21 15:18:26,815 DEBUG subiquity.server.controllers.filesystem:996 suggested install minimum size: 8.549G 2024-02-21 15:18:26,821 DEBUG root:30 finish: subiquity/Filesystem/v2_orig_config_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"... 2024-02-21 15:18:28,305 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:18:28,305 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:18:28,349 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:18:28,350 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-21 15:18:28,353 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:18:28,624 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2024-02-21 15:18:28,625 DEBUG subiquitycore.models.network:467 has_network True 2024-02-21 15:18:28,625 DEBUG subiquity.models.subiquity:256 model network for install stage is configured, to go set() 2024-02-21 15:18:28,625 DEBUG subiquity.models.subiquity:256 model network for postinstall stage is configured, to go set() 2024-02-21 15:18:28,626 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-02-21 15:18:28,628 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2024-02-21 15:18:28,629 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2024-02-21 15:18:28,632 INFO root:30 start: subiquity/Meta/confirm_POST: 2024-02-21 15:18:28,633 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go set() 2024-02-21 15:18:28,633 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go set() 2024-02-21 15:18:28,634 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null 2024-02-21 15:18:28,634 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/wait_confirmation: SUCCESS: 2024-02-21 15:18:28,635 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_apt: 2024-02-21 15:18:28,636 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt 2024-02-21 15:18:28,636 DEBUG root:30 start: subiquity/Mirror/waiting: 2024-02-21 15:18:28,637 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:18:28,639 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2024-02-21 15:18:28,640 DEBUG root:30 finish: subiquity/Mirror/waiting: SUCCESS: 2024-02-21 15:18:28,640 DEBUG subiquity.server.controllers.mirror:202 Iterating over country-mirror 2024-02-21 15:18:28,640 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.en.squashfs'] 2024-02-21 15:18:28,684 DEBUG subiquity.server.controllers.mirror:178 APT output follows 2024-02-21 15:18:28,684 ERROR root:30 finish: subiquity/Install/install/configure_apt: FAIL: Failed to use fsimage: '///cdrom/casper/minimal.en.squashfs' doesn't exist or is invalid 2024-02-21 15:18:28,685 DEBUG subiquity.common.errorreport:398 generating crash report 2024-02-21 15:18:28,696 INFO subiquity.common.errorreport:424 saving crash report 'install failed crashed with ValueError' to /var/crash/1708528708.685616732.install_fail.crash 2024-02-21 15:18:28,696 ERROR root:30 finish: subiquity/Install/install: FAIL: Failed to use fsimage: '///cdrom/casper/minimal.en.squashfs' doesn't exist or is invalid 2024-02-21 15:18:28,697 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-21 15:18:28,699 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2024-02-21 15:18:28,700 INFO root:30 start: subiquity/ErrorReporter/1708528708.685616732.install_fail/add_info: 2024-02-21 15:18:28,701 ERROR subiquity.server.server:415 top level error Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/shutdown.py", line 73, in _wait_install await self.app.controllers.Install.install_task ValueError: Failed to use fsimage: '///cdrom/casper/minimal.en.squashfs' doesn't exist or is invalid 2024-02-21 15:18:28,702 ERROR subiquity.server.server:415 top level error Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/shutdown.py", line 73, in _wait_install await self.app.controllers.Install.install_task ValueError: Failed to use fsimage: '///cdrom/casper/minimal.en.squashfs' doesn't exist or is invalid