2024-03-12 13:47:18,220 INFO subiquity:200 Starting Subiquity server revision 29 of snap /snap/ubuntu-desktop-bootstrap/29 of version 0+git.ea537ca1
2024-03-12 13:47:18,221 INFO subiquity:204 Arguments passed: ['/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-bootstrap/29/etc/subiquity/postinst.d']
2024-03-12 13:47:18,221 DEBUG subiquity:205 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz --- quiet splash\n', _tokens={'splash', '---', 'quiet'}, _values={'BOOT_IMAGE': '/casper/vmlinuz'})
2024-03-12 13:47:18,221 DEBUG subiquity:206 Environment: environ({'SNAP_REVISION': '29', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-desktop-bootstrap/common', 'PATH_ORIG': '/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/bin/subiquity', 'SYSTEMD_EXEC_PID': '3675', 'SNAP_CONTEXT': 'HWEgZLRDA3vcTZDJANUg0FSl3Hv1ekwhHdB2nlARn6FQGGrMlUVf', '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/29/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-desktop-bootstrap', 'SNAP_USER_DATA': '/root/snap/ubuntu-desktop-bootstrap/29', 'INVOCATION_ID': 'fa5ad7552f9c4292a7bbe52c8f3df077', '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/29/lib:/snap/ubuntu-desktop-bootstrap/29/usr/lib:/snap/ubuntu-desktop-bootstrap/29/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/29/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-desktop-bootstrap/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', 'USER': 'root', 'PYTHON': '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-desktop-bootstrap/29', 'SNAP_COMMON': '/var/snap/ubuntu-desktop-bootstrap/common', 'SNAP_VERSION': '0+git.ea537ca1', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': 'HWEgZLRDA3vcTZDJANUg0FSl3Hv1ekwhHdB2nlARn6FQGGrMlUVf', 'SNAP_DATA': '/var/snap/ubuntu-desktop-bootstrap/29', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-bootstrap/29/lib:/snap/ubuntu-desktop-bootstrap/29/usr/lib:/snap/ubuntu-desktop-bootstrap/29/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-bootstrap/29/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-desktop-bootstrap', 'JOURNAL_STREAM': '8:15810', 'PATH': '/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-desktop-bootstrap/29', '_': '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/share/apport'})
2024-03-12 13:47:18,221 DEBUG asyncio:54 Using selector: EpollSelector
2024-03-12 13:47:18,223 DEBUG subiquitycore.prober:33 Prober() init finished, data:None
2024-03-12 13:47:18,283 DEBUG curtin:95 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
2024-03-12 13:47:18,295 DEBUG subiquitycore.netplan:113 config for zz-all-en = {'dhcp4': True, 'match': {'name': 'en*'}}
2024-03-12 13:47:18,295 DEBUG subiquitycore.netplan:113 config for zz-all-eth = {'dhcp4': True, 'match': {'name': 'eth*'}}
2024-03-12 13:47:18,307 DEBUG subiquitycore.utils:77 run_command called: ['dpkg-query', '-W', '-f=${Version}', 'cloud-init']
2024-03-12 13:47:18,348 DEBUG subiquitycore.utils:96 run_command ['dpkg-query', '-W', '-f=${Version}', 'cloud-init'] exited with code 0
2024-03-12 13:47:18,349 DEBUG subiquity.cloudinit:39 cloud-init version: 24.1
2024-03-12 13:47:18,349 DEBUG subiquitycore.utils:119 arun_command called: ['cloud-init', 'status', '--wait', '--format=json']
2024-03-12 13:47:19,119 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:19,119 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-03-12 13:47:20,154 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:20,154 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-03-12 13:47:20,382 DEBUG subiquitycore.utils:133 arun_command ['cloud-init', 'status', '--wait', '--format=json'] exited with code 0
2024-03-12 13:47:20,382 DEBUG subiquity.server.server:583 waited 2.0747947692871094s for cloud-init
2024-03-12 13:47:20,383 DEBUG subiquity.server.server:584 cloud-init status: 'done'
2024-03-12 13:47:20,383 DEBUG subiquity.cloudinit:19 Loaded cloud config from /run/cloud-init/combined-cloud-config.json
2024-03-12 13:47:20,383 DEBUG subiquity.server.server:574 no autoinstall found in cloud-config
2024-03-12 13:47:20,384 DEBUG subiquitycore.utils:77 run_command called: ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys']
2024-03-12 13:47:20,402 DEBUG subiquitycore.utils:96 run_command ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] exited with code 255
2024-03-12 13:47:20,402 DEBUG subiquitycore.ssh:53 ssh-keygen -lf /home/installer/.ssh/authorized_keys failed '/home/installer/.ssh/authorized_keys is not a public key file.\r\n'
2024-03-12 13:47:20,403 DEBUG subiquitycore.utils:77 run_command called: chpasswd
2024-03-12 13:47:20,735 DEBUG subiquitycore.utils:96 run_command chpasswd exited with code 0
2024-03-12 13:47:20,738 DEBUG subiquity.server.server:492 load_autoinstall_config only_early True file None
2024-03-12 13:47:20,738 DEBUG subiquity.server.server:492 load_autoinstall_config only_early False file None
2024-03-12 13:47:20,739 DEBUG subiquitycore.core:118 starting controllers
2024-03-12 13:47:20,745 DEBUG subiquity.server.controllers.kernel:63 Using kernel linux-generic-hwe-22.04 due to /etc/subiquity/kernel-meta-package
2024-03-12 13:47:20,758 DEBUG subiquity.models.source:93 loaded 2 sources from '/cdrom/casper/install-sources.yaml'
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s8'}
2024-03-12 13:47:20,760 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-03-12 13:47:20,760 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'10.0.2.15/24'}
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'}
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 1, 'family': 10, 'scope': 200, 'local': b'fec0::6bbb:f55c:87b:d90c/64'}
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 256, 'family': 10, 'scope': 200, 'local': b'fec0::5054:ff:fe12:3456/64'}
2024-03-12 13:47:20,760 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5054:ff:fe12:3456/64'}
2024-03-12 13:47:20,760 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2024-03-12 13:47:20,833 DEBUG subiquitycore.models.network:475 new_link 1 lo lo
2024-03-12 13:47:20,833 DEBUG subiquitycore.models.network:477 ignoring based on type
2024-03-12 13:47:20,833 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s8'}
2024-03-12 13:47:20,904 DEBUG subiquitycore.models.network:475 new_link 2 enp0s8 eth
2024-03-12 13:47:20,904 DEBUG subiquitycore.models.network:506 new_link 2 enp0s8 with config {'dhcp4': True}
2024-03-12 13:47:20,904 DEBUG root:30 start: subiquity/Network/_send_update: NEW enp0s8
2024-03-12 13:47:20,905 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: NEW enp0s8
2024-03-12 13:47:20,905 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2024-03-12 13:47:20,905 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'10.0.2.15/24'}
2024-03-12 13:47:20,905 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:47:20,905 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:47:20,905 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 640, 'family': 10, 'scope': 254, 'local': b'::1'}
2024-03-12 13:47:20,906 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 1, 'family': 10, 'scope': 200, 'local': b'fec0::6bbb:f55c:87b:d90c/64'}
2024-03-12 13:47:20,906 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 256, 'family': 10, 'scope': 200, 'local': b'fec0::5054:ff:fe12:3456/64'}
2024-03-12 13:47:20,906 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5054:ff:fe12:3456/64'}
2024-03-12 13:47:20,906 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:47:20,906 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2}
2024-03-12 13:47:20,950 DEBUG pyroute2.ndb.131850588350464.sources.localhost:74 init
2024-03-12 13:47:20,951 DEBUG pyroute2.ndb.131850588350464.sources.localhost:384 starting the source
2024-03-12 13:47:20,951 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:74 init
2024-03-12 13:47:20,952 DEBUG pyroute2.ndb.131850588350464.sources.localhost:74 connecting
2024-03-12 13:47:20,952 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:384 starting the source
2024-03-12 13:47:20,952 DEBUG pyroute2.ndb.131850588350464.sources.localhost:74 loading
2024-03-12 13:47:20,961 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:74 connecting
2024-03-12 13:47:20,978 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:74 loading
2024-03-12 13:47:21,007 DEBUG pyroute2.ndb.131850588350464.sources.localhost:74 running
2024-03-12 13:47:21,009 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:74 running
2024-03-12 13:47:21,017 DEBUG pyroute2.ndb.131850588350464.sources.localhost:399 source shutdown
2024-03-12 13:47:21,018 DEBUG pyroute2.ndb.131850588350464.sources.localhost:374 sync
2024-03-12 13:47:21,018 DEBUG pyroute2.ndb.131850588350464.sources.localhost:369 shutdown handled by the main thread
2024-03-12 13:47:21,018 DEBUG pyroute2.ndb.131850588350464.sources.localhost:74 stopped
2024-03-12 13:47:21,018 DEBUG pyroute2.ndb.131850588350464.main:851 flush DB for the target localhost
2024-03-12 13:47:21,019 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:399 source shutdown
2024-03-12 13:47:21,038 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:374 sync
2024-03-12 13:47:21,038 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-03-12 13:47:21,038 DEBUG pyroute2.ndb.131850588350464.sources.localhost/nsmanager:74 stopped
2024-03-12 13:47:21,038 DEBUG pyroute2.ndb.131850588350464.main:851 flush DB for the target localhost/nsmanager
2024-03-12 13:47:21,041 DEBUG subiquitycore.controllers.network:103 default routes True
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'10.0.2.0/24', 'ifindex': 2}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'10.0.2.15', 'ifindex': 2}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'10.0.2.255', 'ifindex': 2}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fec0::/64', 'ifindex': 2}
2024-03-12 13:47:21,042 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2}
2024-03-12 13:47:21,077 DEBUG pyroute2.ndb.131850588352192.sources.localhost:74 init
2024-03-12 13:47:21,077 DEBUG pyroute2.ndb.131850588352192.sources.localhost:384 starting the source
2024-03-12 13:47:21,090 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:74 init
2024-03-12 13:47:21,090 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:384 starting the source
2024-03-12 13:47:21,090 DEBUG pyroute2.ndb.131850588352192.sources.localhost:74 connecting
2024-03-12 13:47:21,090 DEBUG pyroute2.ndb.131850588352192.sources.localhost:74 loading
2024-03-12 13:47:21,091 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:74 connecting
2024-03-12 13:47:21,093 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:74 loading
2024-03-12 13:47:21,096 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:74 running
2024-03-12 13:47:21,111 DEBUG pyroute2.ndb.131850588352192.sources.localhost:74 running
2024-03-12 13:47:21,112 DEBUG pyroute2.ndb.131850588352192.sources.localhost:399 source shutdown
2024-03-12 13:47:21,113 DEBUG pyroute2.ndb.131850588352192.sources.localhost:374 sync
2024-03-12 13:47:21,113 DEBUG pyroute2.ndb.131850588352192.sources.localhost:369 shutdown handled by the main thread
2024-03-12 13:47:21,113 DEBUG pyroute2.ndb.131850588352192.sources.localhost:74 stopped
2024-03-12 13:47:21,113 DEBUG pyroute2.ndb.131850588352192.main:851 flush DB for the target localhost
2024-03-12 13:47:21,114 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:399 source shutdown
2024-03-12 13:47:21,115 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:374 sync
2024-03-12 13:47:21,115 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:369 shutdown handled by the main thread
2024-03-12 13:47:21,115 DEBUG pyroute2.ndb.131850588352192.sources.localhost/nsmanager:74 stopped
2024-03-12 13:47:21,137 DEBUG pyroute2.ndb.131850588352192.main:851 flush DB for the target localhost/nsmanager
2024-03-12 13:47:21,139 DEBUG subiquitycore.controllers.network:103 default routes True
2024-03-12 13:47:21,139 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1}
2024-03-12 13:47:21,139 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::5054:ff:fe12:3456', 'ifindex': 2}
2024-03-12 13:47:21,140 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fec0::5054:ff:fe12:3456', 'ifindex': 2}
2024-03-12 13:47:21,140 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fec0::6bbb:f55c:87b:d90c', 'ifindex': 2}
2024-03-12 13:47:21,140 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2}
2024-03-12 13:47:21,384 DEBUG subiquitycore.core:121 controllers started
2024-03-12 13:47:21,384 INFO root:30 start: subiquity/apply_autoinstall_config: 
2024-03-12 13:47:21,384 DEBUG root:30 start: subiquity/Early/apply_autoinstall_config: 
2024-03-12 13:47:21,403 DEBUG root:30 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,405 DEBUG root:30 start: subiquity/Reporting/apply_autoinstall_config: 
2024-03-12 13:47:21,406 DEBUG root:30 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,406 DEBUG root:30 start: subiquity/Error/apply_autoinstall_config: 
2024-03-12 13:47:21,406 DEBUG root:30 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,407 DEBUG root:30 start: subiquity/Userdata/apply_autoinstall_config: 
2024-03-12 13:47:21,407 DEBUG root:30 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,407 DEBUG subiquity.models.subiquity:256 model userdata for postinstall stage is configured, to go {'ubuntu_pro', 'locale', 'packages', 'snaplist', 'ssh', 'drivers', 'identity'}
2024-03-12 13:47:21,407 DEBUG root:30 start: subiquity/Package/apply_autoinstall_config: 
2024-03-12 13:47:21,407 DEBUG root:30 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,407 DEBUG subiquity.models.subiquity:256 model packages for postinstall stage is configured, to go {'ubuntu_pro', 'locale', 'identity', 'snaplist', 'drivers', 'ssh'}
2024-03-12 13:47:21,408 DEBUG root:30 start: subiquity/Debconf/apply_autoinstall_config: 
2024-03-12 13:47:21,408 DEBUG root:30 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,408 DEBUG subiquity.models.subiquity:256 model debconf_selections for install stage is configured, to go {'filesystem', 'keyboard', 'kernel', 'source'}
2024-03-12 13:47:21,408 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Locale as interactive
2024-03-12 13:47:21,408 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Refresh as interactive
2024-03-12 13:47:21,408 DEBUG root:30 start: subiquity/Kernel/apply_autoinstall_config: 
2024-03-12 13:47:21,408 DEBUG root:30 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,433 DEBUG subiquity.models.subiquity:256 model kernel for install stage is configured, to go {'filesystem', 'keyboard', 'source'}
2024-03-12 13:47:21,434 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Integrity as interactive
2024-03-12 13:47:21,434 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Keyboard as interactive
2024-03-12 13:47:21,434 DEBUG root:30 start: subiquity/Zdev/apply_autoinstall_config: 
2024-03-12 13:47:21,434 DEBUG root:30 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,434 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Source as interactive
2024-03-12 13:47:21,434 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Network as interactive
2024-03-12 13:47:21,434 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping UbuntuPro as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Proxy as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Mirror as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Filesystem as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Identity as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping SSH as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping SnapList as interactive
2024-03-12 13:47:21,435 DEBUG root:30 start: subiquity/Ad/apply_autoinstall_config: 
2024-03-12 13:47:21,435 DEBUG root:30 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Codecs as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Drivers as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping OEM as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping TimeZone as interactive
2024-03-12 13:47:21,435 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Install as interactive
2024-03-12 13:47:21,436 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Updates as interactive
2024-03-12 13:47:21,436 DEBUG root:30 start: subiquity/Late/apply_autoinstall_config: 
2024-03-12 13:47:21,436 DEBUG root:30 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,436 DEBUG subiquity.server.server:469 apply_autoinstall_config: skipping Shutdown as interactive
2024-03-12 13:47:21,436 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2024-03-12 13:47:21,436 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'ubuntu_pro', 'identity', 'snaplist', 'drivers', 'ssh'}
2024-03-12 13:47:21,437 DEBUG root:30 start: subiquity/Refresh/configure_snapd: 
2024-03-12 13:47:21,437 DEBUG root:30 start: subiquity/Refresh/configure_snapd/get_details: 
2024-03-12 13:47:21,458 DEBUG root:30 start: subiquity/Refresh/check_for_update: 
2024-03-12 13:47:21,458 DEBUG subiquitycore.utils:119 arun_command called: ['/usr/sbin/realm', 'discover']
2024-03-12 13:47:21,630 DEBUG root:30 start: subiquity/OEM/load_metapackages_list: 
2024-03-12 13:47:21,630 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_confirmation: 
2024-03-12 13:47:21,630 DEBUG root:30 start: subiquity/Install/install: 
2024-03-12 13:47:21,632 DEBUG subiquity.server.controllers.snaplist:79 loading list of snaps
2024-03-12 13:47:21,690 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/snaps/ubuntu-desktop-bootstrap HTTP/1.1" 200 1372
2024-03-12 13:47:21,831 DEBUG subiquity.server.controllers.snaplist:79 loading list of snaps
2024-03-12 13:47:21,832 DEBUG root:30 start: subiquity/Filesystem/_probe: 
2024-03-12 13:47:21,833 DEBUG root:30 start: subiquity/Refresh/check_for_update: 
2024-03-12 13:47:21,833 DEBUG root:30 start: subiquity/Refresh/check_for_update: 
2024-03-12 13:47:21,834 DEBUG root:30 start: subiquity/SnapList/loader: 
2024-03-12 13:47:21,838 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2024-03-12 13:47:21,844 DEBUG asyncio:54 Using selector: EpollSelector
2024-03-12 13:47:21,846 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:21,846 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 13:47:21,847 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2024-03-12 13:47:21,847 DEBUG root:30 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '0+git.ea537ca1'
2024-03-12 13:47:21,848 DEBUG subiquity.server.controllers.refresh:139 snap tracking , not resetting based on .disk/info
2024-03-12 13:47:21,848 DEBUG root:30 finish: subiquity/Refresh/configure_snapd: SUCCESS: 
2024-03-12 13:47:21,848 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2024-03-12 13:47:21,848 DEBUG root:30 start: subiquity/SnapList/loader/list: 
2024-03-12 13:47:21,883 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2024-03-12 13:47:21,883 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2024-03-12 13:47:21,886 DEBUG root:30 start: subiquity/Refresh/GET: 
2024-03-12 13:47:21,886 DEBUG subiquitycore.utils:133 arun_command ['/usr/sbin/realm', 'discover'] exited with code 1
2024-03-12 13:47:22,041 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:22,047 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 13:47:22,059 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2024-03-12 13:47:22,060 DEBUG subiquity.models.subiquity:256 model ubuntu_pro for postinstall stage is configured, to go {'network', 'codecs', 'identity', 'snaplist', 'timezone', 'drivers', 'ssh'}
2024-03-12 13:47:22,060 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go {'network', 'codecs', 'snaplist', 'timezone', 'drivers', 'identity'}
2024-03-12 13:47:22,061 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go {'network', 'codecs', 'timezone', 'drivers', 'identity'}
2024-03-12 13:47:22,061 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2024-03-12 13:47:22,144 DEBUG probert.dasd:134 Probing DASD devies
2024-03-12 13:47:22,145 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2024-03-12 13:47:22,219 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None
2024-03-12 13:47:22,234 DEBUG root:30 finish: subiquity/SnapList/loader/list: SUCCESS: 
2024-03-12 13:47:22,234 DEBUG subiquity.server.controllers.snaplist:92 fetched list of 21 snaps
2024-03-12 13:47:22,235 DEBUG root:30 start: subiquity/SnapList/loader/fetch/microk8s: 
2024-03-12 13:47:22,252 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2024-03-12 13:47:22,258 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2024-03-12 13:47:22,740 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None
2024-03-12 13:47:22,827 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 1504
2024-03-12 13:47:22,831 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 
2024-03-12 13:47:22,832 DEBUG subiquity.server.controllers.refresh:201 check_for_update received [Snap(id='k1Ml1O9GzSO2QftV0ZlWSbUfQ78nN460', name='thunderbird', status=<SnapStatus.AVAILABLE: 'available'>, version='115.8.1-2', revision='455', channel='latest/stable/ubuntu-24.04', publisher=Publisher(id='canonical', username='canonical', display_name='Canonical'))]
2024-03-12 13:47:22,832 DEBUG root:30 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available
2024-03-12 13:47:22,832 DEBUG root:30 start: subiquity/SnapList/loader/fetch/nextcloud: 
2024-03-12 13:47:22,845 DEBUG root:30 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "0+git.ea537ca1", "ne...
2024-03-12 13:47:23,381 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None
2024-03-12 13:47:23,390 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 
2024-03-12 13:47:23,390 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wekan: 
2024-03-12 13:47:23,990 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None
2024-03-12 13:47:23,994 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 
2024-03-12 13:47:23,994 DEBUG root:30 start: subiquity/SnapList/loader/fetch/kata-containers: 
2024-03-12 13:47:24,181 DEBUG curtin:1369 Extracting storage config from probe data
2024-03-12 13:47:24,181 DEBUG curtin:73 /dev/vda is multipath device member? False
2024-03-12 13:47:24,181 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:47:24,181 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:47:24,181 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:47:24,181 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:47:24,221 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:47:24,221 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2024-03-12 13:47:24,221 DEBUG curtin:73 /dev/sr1 is multipath device member? False
2024-03-12 13:47:24,221 DEBUG curtin:86 /dev/sr1 is multipath device partition? False
2024-03-12 13:47:24,221 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:47:24,221 DEBUG curtin:1376 Sorting extracted configurations
2024-03-12 13:47:24,221 INFO curtin:1396 Validating extracted storage config components
2024-03-12 13:47:24,248 DEBUG curtin:1413 Extracted (unmerged) storage config:
storage:
-   id: disk-vda
    path: /dev/vda
    type: disk

2024-03-12 13:47:24,248 DEBUG curtin:1417 Generating storage config dependencies
2024-03-12 13:47:24,248 DEBUG curtin:1423 Merging storage config dependencies
2024-03-12 13:47:24,249 DEBUG curtin:1428 Merged storage config:
storage:
    config:
    -   id: disk-vda
        path: /dev/vda
        type: disk
    version: 2

2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1531 majmin_to_dev {'253:0': Disk(path='/dev/vda', preserve=True, id='disk-vda', type='disk')}
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:23
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:24
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:5
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 11:0
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 7:0
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:47:24,249 DEBUG subiquity.models.filesystem:1543 considering mount of 0:42
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 0:27
2024-03-12 13:47:24,251 DEBUG subiquity.models.filesystem:1543 considering mount of 0:6
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:36
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:37
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:38
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:12
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:7
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:41
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:21
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:39
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:25
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:34
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:40
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:20
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:35
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:62
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:49
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:64
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:73
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:47:24,252 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:47:24,253 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:47:24,253 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:47:24,253 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:47:24,253 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2024-03-12 13:47:24,253 DEBUG subiquity.server.controllers.filesystem:1320 block probing took 2.4 seconds
2024-03-12 13:47:24,253 DEBUG subiquity.server.controllers.filesystem:1515 start_monitor
2024-03-12 13:47:24,258 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 
2024-03-12 13:47:24,294 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None
2024-03-12 13:47:24,298 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 
2024-03-12 13:47:24,299 DEBUG root:30 start: subiquity/SnapList/loader/fetch/docker: 
2024-03-12 13:47:24,606 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None
2024-03-12 13:47:24,607 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 
2024-03-12 13:47:24,607 DEBUG root:30 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 
2024-03-12 13:47:24,910 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 None
2024-03-12 13:47:24,912 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 
2024-03-12 13:47:24,912 DEBUG root:30 start: subiquity/SnapList/loader/fetch/rocketchat-server: 
2024-03-12 13:47:25,225 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None
2024-03-12 13:47:25,226 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 
2024-03-12 13:47:25,227 DEBUG root:30 start: subiquity/SnapList/loader/fetch/mosquitto: 
2024-03-12 13:47:25,523 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None
2024-03-12 13:47:25,530 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 
2024-03-12 13:47:25,530 DEBUG root:30 start: subiquity/SnapList/loader/fetch/etcd: 
2024-03-12 13:47:25,839 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None
2024-03-12 13:47:25,850 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 
2024-03-12 13:47:25,851 DEBUG root:30 start: subiquity/SnapList/loader/fetch/powershell: 
2024-03-12 13:47:25,979 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:25,979 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 13:47:25,980 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:25,981 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 13:47:25,989 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 13:47:25,994 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2024-03-12 13:47:25,994 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2024-03-12 13:47:26,003 DEBUG root:30 start: subiquity/Locale/GET: 
2024-03-12 13:47:26,003 DEBUG root:30 finish: subiquity/Locale/GET: SUCCESS: 200 "C.UTF-8"
2024-03-12 13:47:26,142 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None
2024-03-12 13:47:26,144 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 
2024-03-12 13:47:26,144 DEBUG root:30 start: subiquity/SnapList/loader/fetch/sabnzbd: 
2024-03-12 13:47:26,445 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None
2024-03-12 13:47:26,446 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 
2024-03-12 13:47:26,446 DEBUG root:30 start: subiquity/SnapList/loader/fetch/wormhole: 
2024-03-12 13:47:26,771 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None
2024-03-12 13:47:26,777 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 
2024-03-12 13:47:26,777 DEBUG root:30 start: subiquity/SnapList/loader/fetch/aws-cli: 
2024-03-12 13:47:27,367 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 None
2024-03-12 13:47:27,368 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 
2024-03-12 13:47:27,368 DEBUG root:30 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 
2024-03-12 13:47:27,682 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1796
2024-03-12 13:47:27,686 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 
2024-03-12 13:47:27,687 DEBUG root:30 start: subiquity/SnapList/loader/fetch/slcli: 
2024-03-12 13:47:27,982 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 None
2024-03-12 13:47:27,986 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 
2024-03-12 13:47:27,987 DEBUG root:30 start: subiquity/SnapList/loader/fetch/doctl: 
2024-03-12 13:47:28,165 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 None
2024-03-12 13:47:28,168 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 
2024-03-12 13:47:28,169 DEBUG root:30 start: subiquity/SnapList/loader/fetch/conjure-up: 
2024-03-12 13:47:28,406 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None
2024-03-12 13:47:28,409 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 
2024-03-12 13:47:28,409 DEBUG root:30 start: subiquity/SnapList/loader/fetch/postgresql10: 
2024-03-12 13:47:28,719 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1523
2024-03-12 13:47:28,721 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 
2024-03-12 13:47:28,721 DEBUG root:30 start: subiquity/SnapList/loader/fetch/heroku: 
2024-03-12 13:47:29,210 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None
2024-03-12 13:47:29,211 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 
2024-03-12 13:47:29,212 DEBUG root:30 start: subiquity/SnapList/loader/fetch/keepalived: 
2024-03-12 13:47:29,519 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None
2024-03-12 13:47:29,521 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 
2024-03-12 13:47:29,523 DEBUG root:30 start: subiquity/SnapList/loader/fetch/prometheus: 
2024-03-12 13:47:29,828 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None
2024-03-12 13:47:29,831 DEBUG root:30 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 
2024-03-12 13:47:29,831 DEBUG root:30 finish: subiquity/SnapList/loader: SUCCESS: 
2024-03-12 13:47:49,622 DEBUG subiquity.server.controllers.integrity:61 casper-md5check results: {'checksum_missmatch': [], 'result': 'pass'}
2024-03-12 13:54:34,871 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-03-12 13:54:34,891 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-03-12 13:54:34,892 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 256, 'family': 10, 'scope': 200, 'local': b'fec0::5054:ff:fe12:3456/64'}
2024-03-12 13:54:34,892 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 1, 'family': 10, 'scope': 200, 'local': b'fec0::6bbb:f55c:87b:d90c/64'}
2024-03-12 13:54:34,892 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 256, 'family': 10, 'scope': 200, 'local': b'fec0::5054:ff:fe12:3456/64'}
2024-03-12 13:54:34,895 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:54:34,897 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:54:34,897 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 1, 'family': 10, 'scope': 200, 'local': b'fec0::6bbb:f55c:87b:d90c/64'}
2024-03-12 13:54:34,898 DEBUG root:30 start: subiquity/Network/_send_update: CHANGE enp0s8
2024-03-12 13:54:34,898 DEBUG root:30 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s8
2024-03-12 13:55:57,999 DEBUG root:30 start: subiquity/Locale/POST: 
2024-03-12 13:55:58,000 DEBUG subiquity.server.controllers.locale:68 de_DE.UTF-8
2024-03-12 13:55:58,003 DEBUG subiquity.models.subiquity:256 model locale for postinstall stage is configured, to go {'network', 'codecs', 'timezone', 'drivers', 'identity'}
2024-03-12 13:55:58,003 DEBUG root:30 finish: subiquity/Locale/POST: SUCCESS: 200 null
2024-03-12 13:55:58,004 DEBUG subiquitycore.utils:119 arun_command called: ['localectl', 'set-locale', 'de_DE.UTF-8']
2024-03-12 13:55:58,938 DEBUG subiquitycore.utils:133 arun_command ['localectl', 'set-locale', 'de_DE.UTF-8'] exited with code 0
2024-03-12 13:56:33,351 DEBUG root:30 start: subiquity/Filesystem/has_rst_GET: 
2024-03-12 13:56:33,356 DEBUG root:30 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false
2024-03-12 13:56:33,359 DEBUG root:30 start: subiquity/Keyboard/GET: 
2024-03-12 13:56:33,402 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "de", "variant": "", "toggle": null}, "layouts": [{"co...
2024-03-12 13:56:33,409 DEBUG root:30 start: subiquity/Keyboard/GET: 
2024-03-12 13:56:33,426 DEBUG root:30 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "de", "variant": "", "toggle": null}, "layouts": [{"co...
2024-03-12 13:56:33,432 DEBUG root:30 start: subiquity/Keyboard/input_source_POST: 
2024-03-12 13:56:33,434 DEBUG subiquitycore.utils:119 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','de')]"]
2024-03-12 13:56:33,538 DEBUG subiquitycore.utils:133 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','de')]"] exited with code 0
2024-03-12 13:56:33,538 DEBUG root:30 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null
2024-03-12 13:56:37,621 DEBUG root:30 start: subiquity/Keyboard/POST: 
2024-03-12 13:56:37,621 DEBUG subiquity.server.controllers.keyboard:221 KeyboardSetting(layout='de', variant='', toggle=None)
2024-03-12 13:56:37,625 DEBUG subiquitycore.utils:119 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only']
2024-03-12 13:56:37,969 DEBUG subiquitycore.utils:133 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0
2024-03-12 13:56:37,969 DEBUG subiquitycore.utils:119 arun_command called: ['/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin/subiquity-loadkeys']
2024-03-12 13:56:37,975 DEBUG subiquitycore.utils:133 arun_command ['/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin/subiquity-loadkeys'] exited with code 0
2024-03-12 13:56:37,975 DEBUG subiquity.models.subiquity:256 model keyboard for install stage is configured, to go {'filesystem', 'network', 'source'}
2024-03-12 13:56:37,976 DEBUG root:30 finish: subiquity/Keyboard/POST: SUCCESS: 200 null
2024-03-12 13:56:41,539 DEBUG root:30 start: subiquity/Refresh/GET: 
2024-03-12 13:56:41,540 DEBUG root:30 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "0+git.ea537ca1", "ne...
2024-03-12 13:56:41,546 DEBUG root:30 start: subiquity/Source/GET: 
2024-03-12 13:56:41,551 DEBUG root:30 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal...
2024-03-12 13:56:41,559 DEBUG root:30 start: subiquity/Drivers/GET: 
2024-03-12 13:56:41,561 DEBUG root:30 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": true, "search_drivers": false}
2024-03-12 13:56:41,561 DEBUG root:30 start: subiquity/Codecs/GET: 
2024-03-12 13:56:41,562 DEBUG root:30 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false}
2024-03-12 13:57:28,977 DEBUG root:30 start: subiquity/Source/GET: 
2024-03-12 13:57:28,978 DEBUG root:30 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal...
2024-03-12 13:57:28,979 DEBUG root:30 start: subiquity/Drivers/GET: 
2024-03-12 13:57:28,979 DEBUG root:30 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": true, "search_drivers": false}
2024-03-12 13:57:28,979 DEBUG root:30 start: subiquity/Codecs/GET: 
2024-03-12 13:57:28,980 DEBUG root:30 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false}
2024-03-12 13:57:35,598 DEBUG root:30 start: subiquity/Source/POST: 
2024-03-12 13:57:35,599 DEBUG subiquity.models.subiquity:256 model source for install stage is configured, to go {'filesystem', 'network'}
2024-03-12 13:57:35,601 DEBUG subiquity.server.controllers.drivers:89 source variant has been set. Querying list of drivers.
2024-03-12 13:57:35,602 DEBUG root:30 finish: subiquity/Source/POST: SUCCESS: 200 null
2024-03-12 13:57:35,603 DEBUG root:30 start: subiquity/Drivers/POST: 
2024-03-12 13:57:35,604 DEBUG subiquity.models.subiquity:256 model drivers for postinstall stage is configured, to go {'network', 'codecs', 'identity', 'timezone'}
2024-03-12 13:57:35,605 DEBUG root:30 finish: subiquity/Drivers/POST: SUCCESS: 200 null
2024-03-12 13:57:35,607 DEBUG root:30 start: subiquity/Codecs/POST: 
2024-03-12 13:57:35,608 DEBUG subiquity.models.subiquity:256 model codecs for postinstall stage is configured, to go {'network', 'identity', 'timezone'}
2024-03-12 13:57:35,608 DEBUG root:30 finish: subiquity/Codecs/POST: SUCCESS: 200 null
2024-03-12 13:57:35,609 DEBUG subiquity.server.controllers.filesystem:416 got system None for variation minimal
2024-03-12 13:57:35,612 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '///cdrom/casper/minimal.enhanced-secureboot.de.squashfs']
2024-03-12 13:57:35,614 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmpbs64ae8e/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:35,634 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.squashfs', '/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:35,694 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.enhanced-secureboot.de.squashfs', '/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:35,733 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.de.squashfs.dir:/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.squashfs.dir:/tmp/tmpbs64ae8e/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpbs64ae8e/root.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:35,772 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'bind', '/tmp/tmpbs64ae8e/root.dir/var/lib/snapd/seed/systems', '/var/lib/snapd/seed/systems']
2024-03-12 13:57:35,795 DEBUG root:30 start: subiquity/Drivers/_list_drivers: 
2024-03-12 13:57:35,795 DEBUG root:30 start: subiquity/Drivers/_list_drivers/wait_apt: 
2024-03-12 13:57:35,797 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 
2024-03-12 13:57:35,863 DEBUG subiquity.server.controllers.filesystem:1528 stop_monitor
2024-03-12 13:57:35,864 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-03-12 13:57:35,882 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-03-12 13:57:35,882 DEBUG subiquity.server.controllers.filesystem:1540 Triggered Probert run on udev event
2024-03-12 13:57:35,882 DEBUG root:30 start: subiquity/Filesystem/_probe: 
2024-03-12 13:57:35,882 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2024-03-12 13:57:35,909 DEBUG asyncio:54 Using selector: EpollSelector
2024-03-12 13:57:35,911 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'bind', '/tmp/tmpbs64ae8e/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1629.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1629.snap']
2024-03-12 13:57:35,979 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'bind', '/tmp/tmpbs64ae8e/root.dir/var/lib/snapd/seed/snaps/pc_161.snap', '/var/lib/snapd/seed/snaps/pc_161.snap']
2024-03-12 13:57:36,144 DEBUG probert.dasd:134 Probing DASD devies
2024-03-12 13:57:36,148 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2024-03-12 13:57:36,165 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2024-03-12 13:57:36,166 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2024-03-12 13:57:36,788 DEBUG curtin:1369 Extracting storage config from probe data
2024-03-12 13:57:36,790 DEBUG curtin:73 /dev/vda is multipath device member? False
2024-03-12 13:57:36,792 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:57:36,792 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:57:36,792 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:57:36,792 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:57:36,860 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:57:36,862 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2024-03-12 13:57:36,862 DEBUG curtin:73 /dev/sr1 is multipath device member? False
2024-03-12 13:57:36,862 DEBUG curtin:86 /dev/sr1 is multipath device partition? False
2024-03-12 13:57:36,862 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:57:36,862 DEBUG curtin:1376 Sorting extracted configurations
2024-03-12 13:57:36,863 INFO curtin:1396 Validating extracted storage config components
2024-03-12 13:57:36,914 DEBUG curtin:1413 Extracted (unmerged) storage config:
storage:
-   id: disk-vda
    path: /dev/vda
    type: disk

2024-03-12 13:57:36,914 DEBUG curtin:1417 Generating storage config dependencies
2024-03-12 13:57:36,915 DEBUG curtin:1423 Merging storage config dependencies
2024-03-12 13:57:36,916 DEBUG curtin:1428 Merged storage config:
storage:
    config:
    -   id: disk-vda
        path: /dev/vda
        type: disk
    version: 2

2024-03-12 13:57:36,918 DEBUG subiquity.models.filesystem:1531 majmin_to_dev {'253:0': Disk(path='/dev/vda', preserve=True, id='disk-vda', type='disk')}
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:23
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:24
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:5
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 11:0
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:0
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 0:42
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:57:36,919 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:57:36,920 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:70
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:70
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:70
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:27
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:6
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:36
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:37
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:38
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:12
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:7
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:41
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:21
2024-03-12 13:57:36,921 DEBUG subiquity.models.filesystem:1543 considering mount of 0:39
2024-03-12 13:57:36,922 DEBUG subiquity.models.filesystem:1543 considering mount of 0:25
2024-03-12 13:57:36,922 DEBUG subiquity.models.filesystem:1543 considering mount of 0:34
2024-03-12 13:57:36,922 DEBUG subiquity.models.filesystem:1543 considering mount of 0:40
2024-03-12 13:57:36,922 DEBUG subiquity.models.filesystem:1543 considering mount of 0:20
2024-03-12 13:57:36,922 DEBUG subiquity.models.filesystem:1543 considering mount of 0:35
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 0:62
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:57:36,924 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:0
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:14
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 7:15
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:70
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:49
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:64
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:73
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:36,925 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:36,925 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2024-03-12 13:57:36,926 DEBUG subiquity.server.controllers.filesystem:1320 block probing took 1.0 seconds
2024-03-12 13:57:36,926 DEBUG subiquity.server.controllers.filesystem:1515 start_monitor
2024-03-12 13:57:36,926 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 
2024-03-12 13:57:41,575 DEBUG urllib3.connectionpool:453 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None
2024-03-12 13:57:41,579 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_161.snap']
2024-03-12 13:57:41,661 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1629.snap']
2024-03-12 13:57:41,773 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'umount', '/var/lib/snapd/seed/systems']
2024-03-12 13:57:41,860 DEBUG curtin:95 Running command ['umount', '/tmp/tmpbs64ae8e/root.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:41,871 DEBUG curtin:95 Running command ['umount', '/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:41,878 DEBUG curtin:95 Running command ['umount', '/tmp/tmpbs64ae8e/minimal.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:41,933 DEBUG curtin:95 Running command ['umount', '/tmp/tmpbs64ae8e/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 13:57:41,942 DEBUG subiquity.server.controllers.filesystem:346 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=<StorageEncryptionSupport.UNAVAILABLE: 'unavailable'>, storage_safety=<StorageSafety.PREFER_ENCRYPTED: 'prefer-encrypted'>, encryption_type=<EncryptionType.NONE: ''>, unavailable_reason='not encrypting device storage as checking TPM gave: secure boot is disabled'))
2024-03-12 13:57:41,954 DEBUG subiquity.server.controllers.filesystem:416 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=<StorageEncryptionSupport.UNAVAILABLE: 'unavailable'>, storage_safety=<StorageSafety.PREFER_ENCRYPTED: 'prefer-encrypted'>, encryption_type=<EncryptionType.NONE: ''>, unavailable_reason='not encrypting device storage as checking TPM gave: secure boot is disabled')) for variation minimal-enhanced-secureboot
2024-03-12 13:57:41,959 DEBUG subiquity.server.controllers.filesystem:442 Disabling core boot based install options as third-party drivers selected
2024-03-12 13:57:41,960 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:57:42,014 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 13:57:42,060 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 
2024-03-12 13:57:42,065 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:57:42,067 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 13:57:42,080 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 
2024-03-12 13:57:42,081 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:57:42,100 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di...
2024-03-12 13:57:42,104 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 
2024-03-12 13:57:42,112 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 []
2024-03-12 13:57:42,113 DEBUG subiquity.server.controllers.filesystem:1528 stop_monitor
2024-03-12 13:57:42,113 DEBUG subiquitycore.utils:77 run_command called: ['udevadm', 'settle', '-t', '0']
2024-03-12 13:57:42,156 DEBUG subiquitycore.utils:96 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0
2024-03-12 13:57:42,157 DEBUG subiquity.server.controllers.filesystem:1540 Triggered Probert run on udev event
2024-03-12 13:57:42,157 DEBUG root:30 start: subiquity/Filesystem/_probe: 
2024-03-12 13:57:42,157 DEBUG root:30 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2024-03-12 13:57:42,158 DEBUG asyncio:54 Using selector: EpollSelector
2024-03-12 13:57:42,283 DEBUG probert.dasd:134 Probing DASD devies
2024-03-12 13:57:42,283 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2024-03-12 13:57:42,294 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2024-03-12 13:57:42,295 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2024-03-12 13:57:42,553 DEBUG curtin:1369 Extracting storage config from probe data
2024-03-12 13:57:42,553 DEBUG curtin:73 /dev/vda is multipath device member? False
2024-03-12 13:57:42,553 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:57:42,553 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:57:42,553 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:57:42,553 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:57:42,565 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:57:42,565 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2024-03-12 13:57:42,565 DEBUG curtin:73 /dev/sr1 is multipath device member? False
2024-03-12 13:57:42,565 DEBUG curtin:86 /dev/sr1 is multipath device partition? False
2024-03-12 13:57:42,565 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:57:42,565 DEBUG curtin:1376 Sorting extracted configurations
2024-03-12 13:57:42,565 INFO curtin:1396 Validating extracted storage config components
2024-03-12 13:57:42,577 DEBUG curtin:1413 Extracted (unmerged) storage config:
storage:
-   id: disk-vda
    path: /dev/vda
    type: disk

2024-03-12 13:57:42,577 DEBUG curtin:1417 Generating storage config dependencies
2024-03-12 13:57:42,577 DEBUG curtin:1423 Merging storage config dependencies
2024-03-12 13:57:42,578 DEBUG curtin:1428 Merged storage config:
storage:
    config:
    -   id: disk-vda
        path: /dev/vda
        type: disk
    version: 2

2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1531 majmin_to_dev {'253:0': Disk(path='/dev/vda', preserve=True, id='disk-vda', type='disk')}
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:23
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:24
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:5
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 11:0
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:0
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 0:42
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:57:42,578 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:27
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:6
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:36
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:37
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:38
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:12
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:7
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:41
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:21
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:39
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:25
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:34
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:40
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:20
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:35
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:62
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:49
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:64
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:73
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:42,579 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:57:42,580 DEBUG root:30 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2024-03-12 13:57:42,580 DEBUG subiquity.server.controllers.filesystem:1320 block probing took 0.4 seconds
2024-03-12 13:57:42,580 DEBUG subiquity.server.controllers.filesystem:1515 start_monitor
2024-03-12 13:57:42,580 DEBUG root:30 finish: subiquity/Filesystem/_probe: SUCCESS: 
2024-03-12 13:58:34,794 DEBUG root:30 start: subiquity/Filesystem/has_bitlocker_GET: 
2024-03-12 13:58:34,795 DEBUG root:30 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 []
2024-03-12 13:58:34,797 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 
2024-03-12 13:58:34,797 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:58:34,798 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 13:58:34,802 DEBUG root:30 start: subiquity/Filesystem/v2_guided_GET: 
2024-03-12 13:58:34,802 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:58:34,804 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di...
2024-03-12 13:58:50,338 DEBUG root:30 start: subiquity/Identity/GET: 
2024-03-12 13:58:50,340 DEBUG root:30 finish: subiquity/Identity/GET: SUCCESS: 200 {"realname": "", "username": "", "crypted_password": "", "hostname": ""}
2024-03-12 13:58:50,348 DEBUG root:30 start: subiquity/Ad/has_support_GET: 
2024-03-12 13:58:50,350 DEBUG root:30 finish: subiquity/Ad/has_support_GET: SUCCESS: 200 true
2024-03-12 13:58:52,220 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,220 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,321 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,321 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,443 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,443 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,463 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,463 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,626 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,627 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,682 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,683 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,875 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,875 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:58:52,939 DEBUG root:30 start: subiquity/Identity/validate_username_GET: 
2024-03-12 13:58:52,939 DEBUG root:30 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2024-03-12 13:59:55,310 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2024-03-12 13:59:55,311 DEBUG subiquity.models.subiquity:256 model active_directory for postinstall stage is configured, to go {'network', 'identity', 'timezone'}
2024-03-12 13:59:55,312 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2024-03-12 13:59:55,358 DEBUG root:30 start: subiquity/Identity/POST: 
2024-03-12 13:59:55,360 DEBUG subiquity.models.subiquity:256 model identity for postinstall stage is configured, to go {'network', 'timezone'}
2024-03-12 13:59:55,360 DEBUG root:30 finish: subiquity/Identity/POST: SUCCESS: 200 null
2024-03-12 13:59:55,363 DEBUG root:30 start: subiquity/TimeZone/GET: 
2024-03-12 13:59:55,365 DEBUG root:30 finish: subiquity/TimeZone/GET: SUCCESS: 200 {"timezone": "Europe/Zurich", "from_geoip": true}
2024-03-12 13:59:57,672 DEBUG root:30 start: subiquity/TimeZone/POST: 
2024-03-12 13:59:57,890 DEBUG subiquity.models.subiquity:256 model timezone for postinstall stage is configured, to go {'network'}
2024-03-12 13:59:57,890 DEBUG root:30 finish: subiquity/TimeZone/POST: SUCCESS: 200 null
2024-03-12 13:59:57,895 DEBUG root:30 start: subiquity/Filesystem/v2_guided_POST: 
2024-03-12 13:59:57,895 DEBUG subiquity.server.controllers.filesystem:1170 GuidedChoiceV2(target=GuidedStorageTargetReformat(disk_id='disk-vda', allowed=[<GuidedCapability.DIRECT: 2>, <GuidedCapability.LVM: 3>, <GuidedCapability.LVM_LUKS: 4>, <GuidedCapability.ZFS: 5>, <GuidedCapability.ZFS_LUKS_KEYSTORE: 6>], disallowed=[GuidedDisallowedCapability(capability=<GuidedCapability.CORE_BOOT_ENCRYPTED: 7>, reason=<GuidedDisallowedCapabilityReason.CORE_BOOT_ENCRYPTION_UNAVAILABLE: 2>, message='not encrypting device storage as checking TPM gave: secure boot is disabled'), GuidedDisallowedCapability(capability=<GuidedCapability.CORE_BOOT_UNENCRYPTED: 8>, reason=<GuidedDisallowedCapabilityReason.THIRD_PARTY_DRIVERS: 4>, message='Enhanced secure boot options cannot currently install third party drivers.')]), capability=<GuidedCapability.ZFS_LUKS_KEYSTORE: 6>, recovery_key=None, sizing_policy=<SizingPolicy.ALL: 2>, reset_partition=False, reset_partition_size=None)
2024-03-12 13:59:57,896 DEBUG subiquity.models.filesystem:2177 adding fat32 to Partition(device=disk-vda, size=1127219200, wipe='superblock', flag='boot', number=1, grub_device=True, offset=1048576, id='partition-0')
2024-03-12 13:59:57,896 DEBUG curtin:37 swap suggestion analysis: available: 25G maxsize: 6G memsize: 3G size before decision: 3G suggested size: 3G reason: lesser of formula or maxsize
2024-03-12 13:59:57,896 DEBUG subiquity.models.filesystem:2177 adding swap to DM_Crypt(volume=partition-2, keyfile='/dev/urandom', options=['swap', 'initramfs'], id='dm_crypt-0')
2024-03-12 13:59:57,898 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:59:57,900 DEBUG root:30 finish: subiquity/Filesystem/v2_guided_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": {"target": {"disk_id":...
2024-03-12 13:59:57,902 DEBUG root:30 start: subiquity/Filesystem/v2_POST: 
2024-03-12 13:59:57,903 DEBUG subiquity.models.subiquity:256 model filesystem for install stage is configured, to go {'network'}
2024-03-12 13:59:57,903 DEBUG subiquity.server.controllers.filesystem:1528 stop_monitor
2024-03-12 13:59:57,903 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:59:57,906 DEBUG root:30 finish: subiquity/Filesystem/v2_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 13:59:57,911 DEBUG root:30 start: subiquity/Filesystem/v2_GET: 
2024-03-12 13:59:57,911 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:59:57,913 DEBUG root:30 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 13:59:57,915 DEBUG root:30 start: subiquity/Filesystem/v2_orig_config_GET: 
2024-03-12 13:59:57,915 DEBUG curtin:1369 Extracting storage config from probe data
2024-03-12 13:59:57,915 DEBUG curtin:73 /dev/vda is multipath device member? False
2024-03-12 13:59:57,915 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:59:57,915 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:59:57,915 DEBUG curtin:86 /dev/vda is multipath device partition? False
2024-03-12 13:59:57,915 DEBUG curtin:61 /dev/vda is multipath device? False
2024-03-12 13:59:57,929 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:59:57,930 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2024-03-12 13:59:57,930 DEBUG curtin:73 /dev/sr1 is multipath device member? False
2024-03-12 13:59:57,930 DEBUG curtin:86 /dev/sr1 is multipath device partition? False
2024-03-12 13:59:57,930 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2024-03-12 13:59:57,930 DEBUG curtin:1376 Sorting extracted configurations
2024-03-12 13:59:57,930 INFO curtin:1396 Validating extracted storage config components
2024-03-12 13:59:57,957 DEBUG curtin:1413 Extracted (unmerged) storage config:
storage:
-   id: disk-vda
    path: /dev/vda
    type: disk

2024-03-12 13:59:57,957 DEBUG curtin:1417 Generating storage config dependencies
2024-03-12 13:59:57,957 DEBUG curtin:1423 Merging storage config dependencies
2024-03-12 13:59:57,959 DEBUG curtin:1428 Merged storage config:
storage:
    config:
    -   id: disk-vda
        path: /dev/vda
        type: disk
    version: 2

2024-03-12 13:59:57,959 DEBUG subiquity.models.filesystem:1531 majmin_to_dev {'253:0': Disk(path='/dev/vda', preserve=True, id='disk-vda', type='disk')}
2024-03-12 13:59:57,959 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:59:57,959 DEBUG subiquity.models.filesystem:1543 considering mount of 0:23
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 0:24
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 0:5
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 11:0
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:0
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 0:42
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:59:57,960 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 0:29
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 0:27
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 0:6
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 0:36
2024-03-12 13:59:57,961 DEBUG subiquity.models.filesystem:1543 considering mount of 0:37
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:38
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:12
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:7
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:41
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:21
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:39
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:25
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:34
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:40
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:20
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:35
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:62
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:26
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:3
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:5
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:4
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:7
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:6
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:13
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:11
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:9
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:12
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:10
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 7:8
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:49
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:64
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:73
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:59:57,962 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:59:57,963 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:59:57,963 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:59:57,963 DEBUG subiquity.models.filesystem:1543 considering mount of 0:4
2024-03-12 13:59:57,963 DEBUG subiquity.server.controllers.filesystem:1014 suggested install minimum size: 8.507G
2024-03-12 13:59:57,968 DEBUG root:30 finish: subiquity/Filesystem/v2_orig_config_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"...
2024-03-12 14:00:03,854 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:03,858 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 14:00:03,910 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:03,910 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2024-03-12 14:00:03,925 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:04,146 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2024-03-12 14:00:04,146 DEBUG subiquitycore.models.network:467 has_network True
2024-03-12 14:00:04,146 DEBUG subiquity.models.subiquity:256 model network for install stage is configured, to go set()
2024-03-12 14:00:04,147 DEBUG subiquity.models.subiquity:256 model network for postinstall stage is configured, to go set()
2024-03-12 14:00:04,147 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2024-03-12 14:00:04,150 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2024-03-12 14:00:04,150 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2024-03-12 14:00:04,162 INFO root:30 start: subiquity/Meta/confirm_POST: 
2024-03-12 14:00:04,162 DEBUG subiquity.models.subiquity:256 model ssh for postinstall stage is configured, to go set()
2024-03-12 14:00:04,162 DEBUG subiquity.models.subiquity:256 model snaplist for postinstall stage is configured, to go set()
2024-03-12 14:00:04,162 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2024-03-12 14:00:04,163 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:04,163 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/wait_confirmation: SUCCESS: 
2024-03-12 14:00:04,163 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/wait_apt: 
2024-03-12 14:00:04,165 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:04,166 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt
2024-03-12 14:00:04,167 DEBUG root:30 start: subiquity/Mirror/waiting: 
2024-03-12 14:00:04,167 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2024-03-12 14:00:04,168 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2024-03-12 14:00:04,168 DEBUG root:30 finish: subiquity/Mirror/waiting: SUCCESS: 
2024-03-12 14:00:04,168 DEBUG subiquity.server.controllers.mirror:202 Iterating over country-mirror
2024-03-12 14:00:04,168 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.de.squashfs']
2024-03-12 14:00:04,168 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmpilwjwaym/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:04,183 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.de.squashfs', '/tmp/tmpilwjwaym/minimal.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:04,216 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmpilwjwaym/minimal.de.squashfs.dir:/tmp/tmpilwjwaym/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpilwjwaym/root.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:04,227 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'lowerdir=/tmp/tmpilwjwaym/root.dir,upperdir=/tmp/tmpae7ccrjl/upper,workdir=/tmp/tmpae7ccrjl/work', '-t', 'overlay', 'overlay', '/tmp/tmpae7ccrjl/mount']
2024-03-12 14:00:04,232 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:04,232 INFO root:30 start: subiquity/Meta/status_GET: 
2024-03-12 14:00:04,346 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.1"}}', '-c', '/var/log/installer/curtin-install/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpae7ccrjl/mount']
2024-03-12 14:00:06,624 DEBUG root:30 start: subiquity/Mirror/cmd-apt-config: curtin command apt-config
2024-03-12 14:00:07,126 DEBUG root:30 finish: subiquity/Mirror/cmd-apt-config: SUCCESS: curtin command apt-config
2024-03-12 14:00:07,145 DEBUG subiquitycore.utils:152 astart_command called: ['apt-get', 'update', '-oAPT::Update::Error-Mode=any', '-oAPT::Sandbox::User=root', '-oAcquire::IndexTargets::deb::Packages::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::Translations::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-small::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::CNF::DefaultEnabled=false', '-oAcquire::IndexTargets::deb-src::Sources::DefaultEnabled=false']
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:176 Mirror checking successful
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:178 APT output follows
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 Holen:1 http://archive.ubuntu.com/ubuntu noble InRelease [255 kB]
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 Holen:2 http://archive.ubuntu.com/ubuntu noble-updates InRelease [90,7 kB]
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 Holen:3 http://archive.ubuntu.com/ubuntu noble-backports InRelease [90,8 kB]
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 OK:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 Es wurden 436 kB in 1 s geholt (344 kB/s).
2024-03-12 14:00:16,851 DEBUG subiquity.server.controllers.mirror:180 Paketlisten werden gelesen…
2024-03-12 14:00:16,852 DEBUG curtin:117 Setting up Layered Source for stack ['///cdrom/casper/minimal.squashfs', '///cdrom/casper/minimal.de.squashfs']
2024-03-12 14:00:16,852 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp8xbzhtsk/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:16,909 DEBUG curtin:95 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.de.squashfs', '/tmp/tmp8xbzhtsk/minimal.de.squashfs.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:16,946 DEBUG curtin:95 Running command ['mount', '-o', 'lowerdir=/tmp/tmp8xbzhtsk/minimal.de.squashfs.dir:/tmp/tmp8xbzhtsk/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp8xbzhtsk/root.dir'] with allowed return codes [0] (capture=True)
2024-03-12 14:00:16,959 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'lowerdir=/tmp/tmp8xbzhtsk/root.dir,upperdir=/tmp/tmpcmpk3ruc/upper,workdir=/tmp/tmpcmpk3ruc/work', '-t', 'overlay', 'overlay', '/tmp/tmpcmpk3ruc/mount']
2024-03-12 14:00:17,118 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.2"}}', '-c', '/var/log/installer/curtin-install/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpcmpk3ruc/mount']
2024-03-12 14:00:19,883 DEBUG root:30 start: subiquity/Mirror/cmd-apt-config: curtin command apt-config
2024-03-12 14:00:20,825 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-03-12 14:00:20,872 DEBUG root:30 finish: subiquity/Mirror/cmd-apt-config: SUCCESS: curtin command apt-config
2024-03-12 14:00:20,926 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-03-12 14:00:20,926 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'lowerdir=/tmp/tmpcmpk3ruc/upper:/tmp/tmp8xbzhtsk/root.dir,upperdir=/tmp/tmpjybfkarf/upper,workdir=/tmp/tmpjybfkarf/work', '-t', 'overlay', 'overlay', '/tmp/tmpjybfkarf/mount']
2024-03-12 14:00:21,004 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmpjybfkarf/mount/cdrom']
2024-03-12 14:00:21,088 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.3"}}', 'in-target', '-t', '/tmp/tmpjybfkarf/mount', '--', 'apt-get', 'update']
2024-03-12 14:00:22,883 DEBUG root:30 start: subiquity/Install/install/configure_apt/cmd-in-target: curtin command in-target
2024-03-12 14:00:45,492 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-03-12 14:00:45,593 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-03-12 14:00:45,623 DEBUG root:30 finish: subiquity/Install/install/configure_apt/cmd-in-target: SUCCESS: curtin command in-target
2024-03-12 14:00:45,696 DEBUG subiquity.server.curtin:130 waited 0.30000000000000004 seconds for events to drain
2024-03-12 14:00:45,698 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt
2024-03-12 14:00:45,699 DEBUG root:30 start: subiquity/Install/install/live-packages: installing packages to live system
2024-03-12 14:00:45,699 DEBUG root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: SUCCESS: 
2024-03-12 14:00:45,700 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'lowerdir=/tmp/tmpjybfkarf/upper:/tmp/tmpcmpk3ruc/upper:/tmp/tmp8xbzhtsk/root.dir,upperdir=/tmp/tmpjkivkp2s/upper,workdir=/tmp/tmpjkivkp2s/work', '-t', 'overlay', 'overlay', '/tmp/tmpjkivkp2s/mount']
2024-03-12 14:00:45,704 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/wait_apt: SUCCESS: 
2024-03-12 14:00:45,705 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'mount', '-o', 'lowerdir=/tmp/tmpjybfkarf/upper:/tmp/tmpcmpk3ruc/upper:/tmp/tmp8xbzhtsk/root.dir,upperdir=/tmp/tmpa2ze1zwo/upper,workdir=/tmp/tmpa2ze1zwo/work', '-t', 'overlay', 'overlay', '/tmp/tmpa2ze1zwo/mount']
2024-03-12 14:00:45,710 DEBUG subiquity.server.pkghelper:65 checking if zfsutils-linux is available
2024-03-12 14:00:48,449 DEBUG subiquity.server.pkghelper:71 zfsutils-linux already installed
2024-03-12 14:00:48,451 DEBUG root:30 finish: subiquity/Install/install/live-packages: SUCCESS: installing packages to live system
2024-03-12 14:00:48,452 INFO root:30 start: subiquity/Install/install/curtin_install: installing system
2024-03-12 14:00:48,454 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install initial step
2024-03-12 14:00:48,459 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.4"}}', '-c', '/var/log/installer/curtin-install/subiquity-initial.conf', 'install', '--set', 'json:stages=[]']
2024-03-12 14:00:48,465 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'chroot', '/tmp/tmpjkivkp2s/mount', 'sh', '-c', 'command -v ubuntu-drivers']
2024-03-12 14:00:48,474 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'chroot', '/tmp/tmpa2ze1zwo/mount', 'sh', '-c', 'command -v ubuntu-drivers']
2024-03-12 14:00:48,608 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.5"}}', 'in-target', '-t', '/tmp/tmpjkivkp2s/mount', '--', 'ubuntu-drivers', 'list', '--recommended']
2024-03-12 14:00:48,650 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--property', 'PrivateMounts=yes', '--pipe', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.6"}}', 'in-target', '-t', '/tmp/tmpa2ze1zwo/mount', '--', 'ubuntu-drivers', 'list-oem']
2024-03-12 14:00:52,606 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: SUCCESS: executing curtin install initial step
2024-03-12 14:00:52,612 DEBUG subiquity.models.filesystem:1906 mountpoints {'/boot/efi': 'mount-0', '': 'mount-1', None: 'zfs-11', '/boot': 'zfs-1', '/': 'zfs-3', 'ROOT/ubuntu_d54sag/var/lib': 'zfs-5', 'ROOT/ubuntu_d54sag/var/lib/AccountsService': 'zfs-6', 'ROOT/ubuntu_d54sag/var/lib/apt': 'zfs-7', 'ROOT/ubuntu_d54sag/var/lib/dpkg': 'zfs-8', 'ROOT/ubuntu_d54sag/var/lib/NetworkManager': 'zfs-9', 'ROOT/ubuntu_d54sag/srv': 'zfs-10', 'ROOT/ubuntu_d54sag/usr/local': 'zfs-12', 'ROOT/ubuntu_d54sag/var/games': 'zfs-13', 'ROOT/ubuntu_d54sag/var/log': 'zfs-14', 'ROOT/ubuntu_d54sag/var/mail': 'zfs-15', 'ROOT/ubuntu_d54sag/var/snap': 'zfs-16', 'ROOT/ubuntu_d54sag/var/spool': 'zfs-17', 'ROOT/ubuntu_d54sag/var/www': 'zfs-18'}
2024-03-12 14:00:52,612 DEBUG subiquity.models.filesystem:1896 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-03-12 14:00:52,615 DEBUG subiquity.models.filesystem:1896 cannot emit action to mount /boot until that for / is emitted
2024-03-12 14:00:52,617 DEBUG subiquity.models.filesystem:1896 cannot emit action to mount /boot/efi until that for /boot is emitted
2024-03-12 14:00:52,617 DEBUG curtin:145 ZFS cannot use swapfiles
2024-03-12 14:00:52,618 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step: executing curtin install partitioning step
2024-03-12 14:00:52,704 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', '/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3695.7"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmpjybfkarf/mount']
2024-03-12 14:00:52,711 DEBUG root:30 start: subiquity/Drivers/_list_drivers/cmd-in-target: curtin command in-target
2024-03-12 14:00:52,713 DEBUG root:30 start: subiquity/OEM/load_metapackages_list/cmd-in-target: curtin command in-target
2024-03-12 14:00:57,126 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: curtin command install
2024-03-12 14:00:57,127 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: configuring storage
2024-03-12 14:00:57,127 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: running 'curtin block-meta simple'
2024-03-12 14:01:00,025 DEBUG subiquity.server.curtin:130 waited 0.1 seconds for events to drain
2024-03-12 14:01:00,119 DEBUG root:30 finish: subiquity/Drivers/_list_drivers/cmd-in-target: SUCCESS: curtin command in-target
2024-03-12 14:01:00,120 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list/cmd-in-target: SUCCESS: curtin command in-target
2024-03-12 14:01:00,127 DEBUG subiquity.server.curtin:130 waited 0.2 seconds for events to drain
2024-03-12 14:01:00,129 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'umount', '/tmp/tmpa2ze1zwo/mount']
2024-03-12 14:01:00,148 DEBUG subiquitycore.utils:152 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3695', '--setenv', 'PATH=/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/bin:/snap/ubuntu-desktop-bootstrap/29/bin/subiquity/usr/bin:/snap/ubuntu-desktop-bootstrap/29/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/sbin:/snap/ubuntu-desktop-bootstrap/29/usr/bin:/snap/ubuntu-desktop-bootstrap/29/sbin:/snap/ubuntu-desktop-bootstrap/29/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/29/lib/python3.10/site-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-bootstrap/29/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-bootstrap/29/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-bootstrap/29', '--', 'umount', '/tmp/tmpjkivkp2s/mount']
2024-03-12 14:01:00,351 DEBUG subiquity.server.controllers.oem:192 OEM meta-packages to install: []
2024-03-12 14:01:00,351 DEBUG root:30 finish: subiquity/OEM/load_metapackages_list: SUCCESS: 
2024-03-12 14:01:00,384 DEBUG subiquity.server.controllers.drivers:119 Available drivers to install: []
2024-03-12 14:01:00,384 DEBUG root:30 finish: subiquity/Drivers/_list_drivers: SUCCESS: 
2024-03-12 14:01:00,940 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta
2024-03-12 14:01:01,214 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: removing previous storage devices
2024-03-12 14:01:01,872 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: SUCCESS: removing previous storage devices
2024-03-12 14:01:01,878 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: configuring disk: disk-vda
2024-03-12 14:01:05,850 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/: SUCCESS: configuring disk: disk-vda
2024-03-12 14:01:05,850 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-0
2024-03-12 14:01:06,587 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-0
2024-03-12 14:01:06,588 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-0
2024-03-12 14:01:08,130 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-0
2024-03-12 14:01:08,131 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-1
2024-03-12 14:01:08,738 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-1
2024-03-12 14:01:08,738 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-2
2024-03-12 14:01:09,377 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-2
2024-03-12 14:01:09,378 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring dm_crypt: dm_crypt-0
2024-03-12 14:01:10,333 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring dm_crypt: dm_crypt-0
2024-03-12 14:01:10,334 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-1
2024-03-12 14:01:10,334 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-1
2024-03-12 14:01:10,335 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-1
2024-03-12 14:01:10,626 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-1
2024-03-12 14:01:10,627 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-3
2024-03-12 14:01:10,887 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-3
2024-03-12 14:01:10,887 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring zpool: zpool-0
2024-03-12 14:01:11,982 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring zpool: zpool-0
2024-03-12 14:01:11,983 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring zfs: zfs-0
2024-03-12 14:01:12,277 DEBUG root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring zfs: zfs-0
2024-03-12 14:01:12,278 DEBUG root:30 start: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring zpool: zpool-1
2024-03-12 14:01:13,083 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: configuring zpool: zpool-1
2024-03-12 14:01:13,084 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: FAIL: running 'curtin block-meta simple'
2024-03-12 14:01:13,374 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: FAIL: configuring storage
2024-03-12 14:01:15,102 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: FAIL: 
2024-03-12 14:01:15,102 ERROR root:30 finish: subiquity/Install/install/curtin_install: FAIL: 
2024-03-12 14:01:15,102 DEBUG subiquity.common.errorreport:398 generating crash report
2024-03-12 14:01:15,173 INFO subiquity.common.errorreport:424 saving crash report 'partitioning crashed with CurtinInstallError' to /var/crash/1710252075.102876902.install_fail.crash
2024-03-12 14:01:15,176 ERROR root:30 finish: subiquity/Install/install: FAIL: 
2024-03-12 14:01:15,177 INFO root:30 start: subiquity/ErrorReporter/1710252075.102876902.install_fail/add_info: 
2024-03-12 14:01:15,178 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install: FAIL: curtin command install