2023-07-27 17:59:56,224 INFO subiquity:163 Starting Subiquity server revision 1081 of snap /snap/ubuntu-desktop-installer/1081 2023-07-27 17:59:56,224 INFO subiquity:164 Arguments passed: ['/snap/ubuntu-desktop-installer/1081/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1081/etc/subiquity/postinst.d'] 2023-07-27 17:59:56,224 DEBUG subiquity:165 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz layerfs-path=minimal.standard.live.squashfs ---\n', _tokens={'---'}, _values={'BOOT_IMAGE': '/casper/vmlinuz', 'layerfs-path': 'minimal.standard.live.squashfs'}) 2023-07-27 17:59:56,224 DEBUG subiquity:166 Environment: environ({'SNAP_REVISION': '1081', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-desktop-installer/common', 'PATH_ORIG': '/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin', 'SNAP_INSTANCE_KEY': '', 'SNAP_EUID': '0', 'PWD': '/snap/ubuntu-desktop-installer/1081/bin/subiquity', 'SYSTEMD_EXEC_PID': '1841', 'SNAP_CONTEXT': '7zvlbpQ0znbfy_b9I41c0xN5MZK8Y9MuuhK1QRdfFlBQJpqRZp1D', 'LANG': 'C.UTF-8', 'SNAP_ARCH': 'amd64', 'PY3OR2_PYTHON': '/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-desktop-installer', 'SNAP_USER_DATA': '/root/snap/ubuntu-desktop-installer/1081', 'INVOCATION_ID': '177aa020e6bf4d19b2750b4c7b15f376', '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-installer/1081/lib:/snap/ubuntu-desktop-installer/1081/usr/lib:/snap/ubuntu-desktop-installer/1081/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-installer/1081/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-desktop-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', 'PYTHON': '/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-desktop-installer/1081', 'SNAP_COMMON': '/var/snap/ubuntu-desktop-installer/common', 'SNAP_VERSION': '0+git.b6180bab', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': '7zvlbpQ0znbfy_b9I41c0xN5MZK8Y9MuuhK1QRdfFlBQJpqRZp1D', 'SNAP_DATA': '/var/snap/ubuntu-desktop-installer/1081', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-installer/1081/lib:/snap/ubuntu-desktop-installer/1081/usr/lib:/snap/ubuntu-desktop-installer/1081/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-installer/1081/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-desktop-installer', 'JOURNAL_STREAM': '8:24064', 'PATH': '/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-desktop-installer/1081', '_': '/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-desktop-installer/1081/bin/subiquity/share/apport'}) 2023-07-27 17:59:56,224 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 17:59:56,225 DEBUG subiquitycore.prober:36 Prober() init finished, data:None 2023-07-27 17:59:56,311 DEBUG curtin:92 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True) 2023-07-27 17:59:56,333 DEBUG subiquitycore.netplan:109 config for zz-all-en = {'dhcp4': True, 'match': {'name': 'en*'}} 2023-07-27 17:59:56,333 DEBUG subiquitycore.netplan:109 config for zz-all-eth = {'dhcp4': True, 'match': {'name': 'eth*'}} 2023-07-27 17:59:56,354 DEBUG subiquitycore.utils:96 arun_command called: ['cloud-init', 'status', '--wait'] 2023-07-27 17:59:57,808 DEBUG subiquitycore.utils:106 arun_command ['cloud-init', 'status', '--wait'] exited with code 0 2023-07-27 17:59:57,808 DEBUG subiquity.server.server:547 waited 1.4534039497375488s for cloud-init 2023-07-27 17:59:57,808 DEBUG subiquity.server.server:549 loading cloud config 2023-07-27 17:59:57,808 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg (quiet=False) 2023-07-27 17:59:57,808 DEBUG cloudinit.util:1547 Read 3487 bytes from /etc/cloud/cloud.cfg 2023-07-27 17:59:57,808 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 3487 with allowed root types (,) 2023-07-27 17:59:57,816 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2023-07-27 17:59:57,816 DEBUG cloudinit.util:1547 Read 320 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2023-07-27 17:59:57,816 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 320 with allowed root types (,) 2023-07-27 17:59:57,817 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/06_quiet.cfg (quiet=False) 2023-07-27 17:59:57,817 DEBUG cloudinit.util:1547 Read 112 bytes from /etc/cloud/cloud.cfg.d/06_quiet.cfg 2023-07-27 17:59:57,817 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 112 with allowed root types (,) 2023-07-27 17:59:57,818 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2023-07-27 17:59:57,818 DEBUG cloudinit.util:1547 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2023-07-27 17:59:57,818 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 2070 with allowed root types (,) 2023-07-27 17:59:57,822 DEBUG cloudinit.util:1536 Reading from /run/cloud-init/cloud.cfg (quiet=False) 2023-07-27 17:59:57,822 DEBUG cloudinit.util:1547 Read 35 bytes from /run/cloud-init/cloud.cfg 2023-07-27 17:59:57,822 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 35 with allowed root types (,) 2023-07-27 17:59:57,822 DEBUG cloudinit.subp:245 Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) 2023-07-27 17:59:57,826 DEBUG cloudinit.util:1536 Reading from /etc/os-release (quiet=False) 2023-07-27 17:59:57,826 DEBUG cloudinit.util:1547 Read 424 bytes from /etc/os-release 2023-07-27 17:59:57,827 DEBUG cloudinit.util:1536 Reading from /proc/1/environ (quiet=False) 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1547 Read 310 bytes from /proc/1/environ 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1536 Reading from /proc/self/status (quiet=False) 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1547 Read 1386 bytes from /proc/self/status 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1536 Reading from /proc/cmdline (quiet=False) 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1547 Read 75 bytes from /proc/cmdline 2023-07-27 17:59:57,828 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 0 with allowed root types (,) 2023-07-27 17:59:57,828 DEBUG cloudinit.util:954 loaded blob returned None, returning default. 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2023-07-27 17:59:57,828 DEBUG cloudinit.util:1547 Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2023-07-27 17:59:57,828 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 0 with allowed root types (,) 2023-07-27 17:59:57,829 DEBUG cloudinit.util:954 loaded blob returned None, returning default. 2023-07-27 17:59:57,829 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2023-07-27 17:59:57,829 DEBUG cloudinit.util:1547 Read 7864 bytes from /var/lib/cloud/instance/obj.pkl 2023-07-27 17:59:57,838 WARNING cloudinit.sources:1715 Failed loading pickled blob from /var/lib/cloud/instance/obj.pkl 2023-07-27 17:59:57,838 DEBUG cloudinit.sources:1724 Failed loading pickled blob from /var/lib/cloud/instance/obj.pkl Traceback (most recent call last): File "/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages/cloudinit/sources/__init__.py", line 1155, in pkl_load return pickle.loads(pickle_contents) AttributeError: Can't get attribute 'IscDhclient' on 2023-07-27 17:59:57,838 DEBUG cloudinit.stages:320 no cache found 2023-07-27 17:59:57,838 DEBUG cloudinit.util:1996 Attempting to remove /var/lib/cloud/instance 2023-07-27 17:59:57,839 DEBUG cloudinit.stages:136 Using distro class 2023-07-27 17:59:57,839 DEBUG cloudinit.sources:1018 Looking for data source in: ['NoCloud', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK'] 2023-07-27 17:59:57,840 DEBUG cloudinit.sources:985 Searching for network data source in: ['DataSourceNoCloudNet', 'DataSourceNone'] 2023-07-27 17:59:57,840 DEBUG cloudinit.sources:996 Seeing if we can get any data from 2023-07-27 17:59:57,840 DEBUG cloudinit.sources:869 Update datasource metadata and network config due to events: boot-new-instance 2023-07-27 17:59:57,841 DEBUG cloudinit.sources:349 Machine is configured to run on single datasource DataSourceNoCloudNet [seed=None][dsmode=net]. 2023-07-27 17:59:57,841 DEBUG cloudinit.dmi:71 querying dmi data /sys/class/dmi/id/product_serial 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/seed/nocloud/user-data (quiet=False) 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1547 Read 0 bytes from /var/lib/cloud/seed/nocloud/user-data 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False) 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1547 Read 0 bytes from /var/lib/cloud/seed/nocloud/meta-data 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/seed/nocloud/vendor-data (quiet=False) 2023-07-27 17:59:57,841 DEBUG cloudinit.util:1536 Reading from /var/lib/cloud/seed/nocloud/network-config (quiet=False) 2023-07-27 17:59:57,841 DEBUG cloudinit.sources.DataSourceNoCloud:98 Using seeded data from /var/lib/cloud/seed/nocloud 2023-07-27 17:59:57,841 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 0 with allowed root types (,) 2023-07-27 17:59:57,841 DEBUG cloudinit.util:954 loaded blob returned None, returning default. 2023-07-27 17:59:57,841 DEBUG cloudinit.subp:245 Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2023-07-27 17:59:57,856 DEBUG cloudinit.subp:245 Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2023-07-27 17:59:57,863 DEBUG cloudinit.subp:245 Running command ['blkid', '-tLABEL=CIDATA', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2023-07-27 17:59:57,870 DEBUG cloudinit.subp:245 Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2023-07-27 17:59:57,877 DEBUG cloudinit.subp:245 Running command ['blkid', '-tLABEL_FATBOOT=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True) 2023-07-27 17:59:57,883 DEBUG cloudinit.util:1536 Reading from /etc/hosts (quiet=False) 2023-07-27 17:59:57,883 DEBUG cloudinit.util:1547 Read 243 bytes from /etc/hosts 2023-07-27 17:59:57,884 DEBUG cloudinit.util:2204 Writing to /run/cloud-init/cloud-id-nocloud - wb: [644] 8 bytes 2023-07-27 17:59:57,885 DEBUG cloudinit.util:1984 Creating symbolic link from '/run/cloud-init/cloud-id' => '/run/cloud-init/cloud-id-nocloud' 2023-07-27 17:59:57,885 DEBUG cloudinit.atomic_helper:30 Atomically writing to file /run/cloud-init/instance-data-sensitive.json (via temporary file /run/cloud-init/tmpndosn0_3) - w: [600] 4397 bytes/chars 2023-07-27 17:59:57,886 DEBUG cloudinit.atomic_helper:30 Atomically writing to file /run/cloud-init/instance-data.json (via temporary file /run/cloud-init/tmpj38ij5v3) - w: [644] 1418 bytes/chars 2023-07-27 17:59:57,886 INFO cloudinit.stages:336 Loaded datasource DataSourceNoCloudNet - DataSourceNoCloudNet [seed=/var/lib/cloud/seed/nocloud][dsmode=net] 2023-07-27 17:59:57,886 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg (quiet=False) 2023-07-27 17:59:57,886 DEBUG cloudinit.util:1547 Read 3487 bytes from /etc/cloud/cloud.cfg 2023-07-27 17:59:57,886 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 3487 with allowed root types (,) 2023-07-27 17:59:57,906 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2023-07-27 17:59:57,906 DEBUG cloudinit.util:1547 Read 320 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2023-07-27 17:59:57,906 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 320 with allowed root types (,) 2023-07-27 17:59:57,909 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/06_quiet.cfg (quiet=False) 2023-07-27 17:59:57,909 DEBUG cloudinit.util:1547 Read 112 bytes from /etc/cloud/cloud.cfg.d/06_quiet.cfg 2023-07-27 17:59:57,909 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 112 with allowed root types (,) 2023-07-27 17:59:57,910 DEBUG cloudinit.util:1536 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2023-07-27 17:59:57,910 DEBUG cloudinit.util:1547 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2023-07-27 17:59:57,910 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 2070 with allowed root types (,) 2023-07-27 17:59:57,916 DEBUG cloudinit.util:1536 Reading from /run/cloud-init/cloud.cfg (quiet=False) 2023-07-27 17:59:57,917 DEBUG cloudinit.util:1547 Read 35 bytes from /run/cloud-init/cloud.cfg 2023-07-27 17:59:57,917 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 35 with allowed root types (,) 2023-07-27 17:59:57,917 DEBUG cloudinit.util:946 Attempting to load yaml from string of length 0 with allowed root types (,) 2023-07-27 17:59:57,918 DEBUG cloudinit.util:954 loaded blob returned None, returning default. 2023-07-27 17:59:57,919 DEBUG cloudinit.stages:136 Using distro class 2023-07-27 17:59:57,921 DEBUG subiquitycore.utils:68 run_command called: ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] 2023-07-27 17:59:57,927 DEBUG subiquitycore.utils:81 run_command ['ssh-keygen', '-lf', '/home/installer/.ssh/authorized_keys'] exited with code 255 2023-07-27 17:59:57,927 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' 2023-07-27 17:59:57,927 DEBUG subiquitycore.utils:68 run_command called: chpasswd 2023-07-27 17:59:58,163 DEBUG subiquitycore.utils:81 run_command chpasswd exited with code 0 2023-07-27 17:59:58,166 DEBUG subiquity.server.server:494 load_autoinstall_config only_early True file None 2023-07-27 17:59:58,166 DEBUG subiquity.server.server:494 load_autoinstall_config only_early False file None 2023-07-27 17:59:58,166 DEBUG subiquitycore.core:123 starting controllers 2023-07-27 17:59:58,169 DEBUG subiquity.server.controllers.kernel:69 Using default kernel linux-generic 2023-07-27 17:59:58,175 DEBUG subiquity.models.source:94 loaded 2 sources from '/cdrom/casper/install-sources.yaml' 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp1s0'} 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2023-07-27 17:59:58,176 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5054:ff:febb:d87/64'} 2023-07-27 17:59:58,176 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2023-07-27 17:59:58,228 DEBUG subiquitycore.models.network:436 new_link 1 lo lo 2023-07-27 17:59:58,228 DEBUG subiquitycore.models.network:438 ignoring based on type 2023-07-27 17:59:58,229 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp1s0'} 2023-07-27 17:59:58,298 DEBUG subiquitycore.models.network:436 new_link 2 enp1s0 eth 2023-07-27 17:59:58,299 DEBUG subiquitycore.models.network:467 new_link 2 enp1s0 with config {'dhcp4': True} 2023-07-27 17:59:58,299 DEBUG root:37 start: subiquity/Network/_send_update: NEW enp1s0 2023-07-27 17:59:58,299 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 17:59:58,299 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: NEW enp1s0 2023-07-27 17:59:58,299 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2023-07-27 17:59:58,299 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 17:59:58,300 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 17:59:58,300 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 17:59:58,300 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 17:59:58,300 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2023-07-27 17:59:58,300 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::5054:ff:febb:d87/64'} 2023-07-27 17:59:58,300 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 17:59:58,300 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 17:59:58,300 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 17:59:58,300 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-07-27 17:59:58,327 DEBUG pyroute2.ndb.140336874338288.sources.localhost:74 init 2023-07-27 17:59:58,327 DEBUG pyroute2.ndb.140336874338288.sources.localhost:384 starting the source 2023-07-27 17:59:58,328 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:74 init 2023-07-27 17:59:58,328 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:384 starting the source 2023-07-27 17:59:58,328 DEBUG pyroute2.ndb.140336874338288.sources.localhost:74 connecting 2023-07-27 17:59:58,328 DEBUG pyroute2.ndb.140336874338288.sources.localhost:74 loading 2023-07-27 17:59:58,329 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:74 connecting 2023-07-27 17:59:58,330 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:74 loading 2023-07-27 17:59:58,348 DEBUG pyroute2.ndb.140336874338288.sources.localhost:74 running 2023-07-27 17:59:58,355 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:74 running 2023-07-27 17:59:58,368 DEBUG pyroute2.ndb.140336874338288.sources.localhost:399 source shutdown 2023-07-27 17:59:58,371 DEBUG pyroute2.ndb.140336874338288.sources.localhost:374 sync 2023-07-27 17:59:58,372 DEBUG pyroute2.ndb.140336874338288.sources.localhost:369 shutdown handled by the main thread 2023-07-27 17:59:58,372 DEBUG pyroute2.ndb.140336874338288.sources.localhost:74 stopped 2023-07-27 17:59:58,372 DEBUG pyroute2.ndb.140336874338288.main:851 flush DB for the target localhost 2023-07-27 17:59:58,373 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:399 source shutdown 2023-07-27 17:59:58,375 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:374 sync 2023-07-27 17:59:58,375 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-07-27 17:59:58,375 DEBUG pyroute2.ndb.140336874338288.sources.localhost/nsmanager:74 stopped 2023-07-27 17:59:58,375 DEBUG pyroute2.ndb.140336874338288.main:851 flush DB for the target localhost/nsmanager 2023-07-27 17:59:58,377 DEBUG subiquitycore.controllers.network:110 default routes True 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'169.254.0.0/16', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.122.0/24', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.122.30', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.122.255', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'::1', 'ifindex': 1} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::5054:ff:febb:d87', 'ifindex': 2} 2023-07-27 17:59:58,378 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2} 2023-07-27 17:59:58,417 DEBUG subiquitycore.core:126 controllers started 2023-07-27 17:59:58,417 INFO root:37 start: subiquity/apply_autoinstall_config: 2023-07-27 17:59:58,417 DEBUG root:37 start: subiquity/Early/apply_autoinstall_config: 2023-07-27 17:59:58,418 DEBUG root:37 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,419 DEBUG root:37 start: subiquity/Reporting/apply_autoinstall_config: 2023-07-27 17:59:58,420 DEBUG root:37 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,420 DEBUG root:37 start: subiquity/Error/apply_autoinstall_config: 2023-07-27 17:59:58,420 DEBUG root:37 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,420 DEBUG root:37 start: subiquity/Userdata/apply_autoinstall_config: 2023-07-27 17:59:58,421 DEBUG root:37 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,421 DEBUG subiquity.models.subiquity:255 model userdata for postinstall stage is configured, to go {'drivers', 'ubuntu_pro', 'ssh', 'snaplist', 'network', 'locale', 'packages', 'identity'} 2023-07-27 17:59:58,421 DEBUG root:37 start: subiquity/Package/apply_autoinstall_config: 2023-07-27 17:59:58,421 DEBUG root:37 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,422 DEBUG subiquity.models.subiquity:255 model packages for postinstall stage is configured, to go {'identity', 'network', 'ubuntu_pro', 'ssh', 'snaplist', 'locale', 'drivers'} 2023-07-27 17:59:58,422 DEBUG root:37 start: subiquity/Debconf/apply_autoinstall_config: 2023-07-27 17:59:58,422 DEBUG root:37 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,422 DEBUG subiquity.models.subiquity:255 model debconf_selections for install stage is configured, to go {'source', 'filesystem', 'keyboard', 'proxy', 'mirror', 'network', 'kernel'} 2023-07-27 17:59:58,422 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Locale as interactive 2023-07-27 17:59:58,422 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Refresh as interactive 2023-07-27 17:59:58,422 DEBUG root:37 start: subiquity/Kernel/apply_autoinstall_config: 2023-07-27 17:59:58,422 DEBUG root:37 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,423 DEBUG subiquity.models.subiquity:255 model kernel for install stage is configured, to go {'source', 'filesystem', 'keyboard', 'proxy', 'mirror', 'network'} 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Integrity as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Keyboard as interactive 2023-07-27 17:59:58,423 DEBUG root:37 start: subiquity/Zdev/apply_autoinstall_config: 2023-07-27 17:59:58,423 DEBUG root:37 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Source as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Network as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping UbuntuPro as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Proxy as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Mirror as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Filesystem as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Identity as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping SSH as interactive 2023-07-27 17:59:58,423 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping SnapList as interactive 2023-07-27 17:59:58,424 DEBUG root:37 start: subiquity/Ad/apply_autoinstall_config: 2023-07-27 17:59:58,424 DEBUG root:37 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Codecs as interactive 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Drivers as interactive 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping OEM as interactive 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping TimeZone as interactive 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Install as interactive 2023-07-27 17:59:58,424 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Updates as interactive 2023-07-27 17:59:58,424 DEBUG root:37 start: subiquity/Late/apply_autoinstall_config: 2023-07-27 17:59:58,424 DEBUG root:37 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,425 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Shutdown as interactive 2023-07-27 17:59:58,425 INFO root:37 finish: subiquity/apply_autoinstall_config: SUCCESS: 2023-07-27 17:59:58,425 DEBUG subiquity.models.subiquity:255 model locale for postinstall stage is configured, to go {'identity', 'network', 'ubuntu_pro', 'ssh', 'snaplist', 'drivers'} 2023-07-27 17:59:58,425 DEBUG root:37 start: subiquity/Refresh/configure_snapd: 2023-07-27 17:59:58,425 DEBUG root:37 start: subiquity/Refresh/configure_snapd/get_details: 2023-07-27 17:59:58,425 DEBUG root:37 start: subiquity/Refresh/check_for_update: 2023-07-27 17:59:58,428 DEBUG root:37 start: subiquity/OEM/load_metapackages_list: 2023-07-27 17:59:58,428 DEBUG root:37 start: subiquity/OEM/load_metapackages_list/wait_apt: 2023-07-27 17:59:58,429 DEBUG root:37 start: subiquity/Install/install: 2023-07-27 17:59:58,432 DEBUG subiquity.server.controllers.snaplist:87 loading list of snaps 2023-07-27 17:59:58,464 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/snaps/ubuntu-desktop-installer HTTP/1.1" 200 1612 2023-07-27 17:59:58,498 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 17:59:58,498 DEBUG root:37 start: subiquity/Refresh/check_for_update: 2023-07-27 17:59:58,498 DEBUG root:37 start: subiquity/SnapList/loader: 2023-07-27 17:59:58,499 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 17:59:58,500 ERROR root:37 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2023-07-27 17:59:58,500 DEBUG root:37 start: subiquity/SnapList/loader/list: 2023-07-27 17:59:58,501 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 17:59:58,501 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 17:59:58,501 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 17:59:58,506 DEBUG root:37 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '0+git.b6180bab' 2023-07-27 17:59:58,506 DEBUG subiquity.server.controllers.refresh:148 snap tracking , not resetting based on .disk/info 2023-07-27 17:59:58,506 DEBUG root:37 finish: subiquity/Refresh/configure_snapd: SUCCESS: 2023-07-27 17:59:59,022 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 59 2023-07-27 17:59:59,023 DEBUG subiquity.server.controllers.refresh:211 check_for_update received [] 2023-07-27 17:59:59,023 DEBUG root:37 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available 2023-07-27 17:59:59,122 DEBUG subiquity.server.geoip:123 no CountryCode found in '10.172.68.114IP NOT FOUND\n' 2023-07-27 17:59:59,185 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 17:59:59,186 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 17:59:59,193 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None 2023-07-27 17:59:59,194 DEBUG root:37 finish: subiquity/SnapList/loader/list: SUCCESS: 2023-07-27 17:59:59,194 DEBUG subiquity.server.controllers.snaplist:100 fetched list of 23 snaps 2023-07-27 17:59:59,195 DEBUG root:37 start: subiquity/SnapList/loader/fetch/microk8s: 2023-07-27 17:59:59,554 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 17:59:59,554 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 17:59:59,554 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 17:59:59,554 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 17:59:59,555 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 17:59:59,555 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 17:59:59,565 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 17:59:59,565 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 17:59:59,565 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 17:59:59,565 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 17:59:59,565 INFO curtin:1342 Validating extracted storage config components 2023-07-27 17:59:59,581 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 17:59:59,581 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 17:59:59,582 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 17:59:59,582 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 17:59:59,582 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 17:59:59,582 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 17:59:59,582 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 1.1 seconds 2023-07-27 17:59:59,582 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 17:59:59,675 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None 2023-07-27 17:59:59,685 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 2023-07-27 17:59:59,685 DEBUG root:37 start: subiquity/SnapList/loader/fetch/nextcloud: 2023-07-27 17:59:59,997 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None 2023-07-27 17:59:59,999 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 2023-07-27 17:59:59,999 DEBUG root:37 start: subiquity/SnapList/loader/fetch/wekan: 2023-07-27 18:00:00,264 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None 2023-07-27 18:00:00,265 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 2023-07-27 18:00:00,265 DEBUG root:37 start: subiquity/SnapList/loader/fetch/kata-containers: 2023-07-27 18:00:00,509 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None 2023-07-27 18:00:00,510 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 2023-07-27 18:00:00,510 DEBUG root:37 start: subiquity/SnapList/loader/fetch/docker: 2023-07-27 18:00:00,770 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None 2023-07-27 18:00:00,771 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 2023-07-27 18:00:00,771 DEBUG root:37 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 2023-07-27 18:00:01,050 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 None 2023-07-27 18:00:01,052 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 2023-07-27 18:00:01,052 DEBUG root:37 start: subiquity/SnapList/loader/fetch/rocketchat-server: 2023-07-27 18:00:01,382 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None 2023-07-27 18:00:01,383 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 2023-07-27 18:00:01,383 DEBUG root:37 start: subiquity/SnapList/loader/fetch/mosquitto: 2023-07-27 18:00:01,691 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None 2023-07-27 18:00:01,692 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 2023-07-27 18:00:01,693 DEBUG root:37 start: subiquity/SnapList/loader/fetch/etcd: 2023-07-27 18:00:02,191 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None 2023-07-27 18:00:02,193 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 2023-07-27 18:00:02,193 DEBUG root:37 start: subiquity/SnapList/loader/fetch/powershell: 2023-07-27 18:00:02,665 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None 2023-07-27 18:00:02,666 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 2023-07-27 18:00:02,666 DEBUG root:37 start: subiquity/SnapList/loader/fetch/stress-ng: 2023-07-27 18:00:03,278 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=stress-ng HTTP/1.1" 200 None 2023-07-27 18:00:03,279 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/stress-ng: SUCCESS: 2023-07-27 18:00:03,279 DEBUG root:37 start: subiquity/SnapList/loader/fetch/sabnzbd: 2023-07-27 18:00:03,892 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None 2023-07-27 18:00:03,892 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 2023-07-27 18:00:03,893 DEBUG root:37 start: subiquity/SnapList/loader/fetch/wormhole: 2023-07-27 18:00:04,557 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None 2023-07-27 18:00:04,558 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 2023-07-27 18:00:04,558 DEBUG root:37 start: subiquity/SnapList/loader/fetch/aws-cli: 2023-07-27 18:00:05,228 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 None 2023-07-27 18:00:05,229 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 2023-07-27 18:00:05,229 DEBUG root:37 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 2023-07-27 18:00:05,816 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1799 2023-07-27 18:00:05,817 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 2023-07-27 18:00:05,817 DEBUG root:37 start: subiquity/SnapList/loader/fetch/slcli: 2023-07-27 18:00:06,389 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 None 2023-07-27 18:00:06,390 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 2023-07-27 18:00:06,391 DEBUG root:37 start: subiquity/SnapList/loader/fetch/doctl: 2023-07-27 18:00:07,065 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 None 2023-07-27 18:00:07,066 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 2023-07-27 18:00:07,066 DEBUG root:37 start: subiquity/SnapList/loader/fetch/conjure-up: 2023-07-27 18:00:07,690 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None 2023-07-27 18:00:07,692 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 2023-07-27 18:00:07,692 DEBUG root:37 start: subiquity/SnapList/loader/fetch/postgresql10: 2023-07-27 18:00:08,388 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1523 2023-07-27 18:00:08,388 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 2023-07-27 18:00:08,389 DEBUG root:37 start: subiquity/SnapList/loader/fetch/heroku: 2023-07-27 18:00:08,951 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None 2023-07-27 18:00:08,952 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 2023-07-27 18:00:08,952 DEBUG root:37 start: subiquity/SnapList/loader/fetch/keepalived: 2023-07-27 18:00:09,498 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None 2023-07-27 18:00:09,498 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 2023-07-27 18:00:09,498 DEBUG root:37 start: subiquity/SnapList/loader/fetch/prometheus: 2023-07-27 18:00:10,159 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None 2023-07-27 18:00:10,160 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 2023-07-27 18:00:10,160 DEBUG root:37 start: subiquity/SnapList/loader/fetch/juju: 2023-07-27 18:00:10,953 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=juju HTTP/1.1" 200 None 2023-07-27 18:00:10,954 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/juju: SUCCESS: 2023-07-27 18:00:10,954 DEBUG root:37 finish: subiquity/SnapList/loader: SUCCESS: 2023-07-27 18:01:04,851 DEBUG subiquity.server.controllers.integrity:63 casper-md5check results: {'checksum_missmatch': [], 'result': 'pass'} 2023-07-27 18:01:21,305 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 18:01:21,306 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 18:01:21,306 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:21,308 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 18:01:21,308 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 18:01:21,308 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:21,583 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 18:01:21,701 INFO root:37 start: subiquity/Meta/client_variant_POST: 2023-07-27 18:01:21,701 INFO root:37 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-07-27 18:01:21,701 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:21,789 DEBUG root:37 start: subiquity/Source/GET: 2023-07-27 18:01:21,790 DEBUG root:37 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal... 2023-07-27 18:01:21,790 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "GET /source HTTP/1.1" 200 606 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:21,807 DEBUG root:37 start: subiquity/Source/POST: 2023-07-27 18:01:21,807 DEBUG subiquity.models.subiquity:255 model source for install stage is configured, to go {'filesystem', 'keyboard', 'proxy', 'mirror', 'network'} 2023-07-27 18:01:21,807 DEBUG subiquity.server.controllers.drivers:92 source variant has been set. Querying list of drivers. 2023-07-27 18:01:21,808 DEBUG root:37 finish: subiquity/Source/POST: SUCCESS: 200 null 2023-07-27 18:01:21,808 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:21,808 DEBUG subiquity.server.controllers.filesystem:376 got system None for variation classic 2023-07-27 18:01:21,808 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp1u6fluv3/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:21,899 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmp1u6fluv3/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:21,913 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.enhanced-secureboot.squashfs', '/tmp/tmp1u6fluv3/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:21,942 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmp1u6fluv3/minimal.standard.enhanced-secureboot.squashfs.dir:/tmp/tmp1u6fluv3/minimal.standard.squashfs.dir:/tmp/tmp1u6fluv3/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp1u6fluv3/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:21,948 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/systems/enhanced-secureboot-desktop', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 18:01:21,956 DEBUG root:37 start: subiquity/Drivers/_list_drivers: 2023-07-27 18:01:21,956 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt: 2023-07-27 18:01:21,956 INFO root:37 start: subiquity/Meta/mark_configured_POST: 2023-07-27 18:01:21,956 DEBUG subiquity.models.subiquity:255 model ubuntu_pro for postinstall stage is configured, to go {'identity', 'network', 'ssh', 'codecs', 'snaplist', 'timezone', 'drivers'} 2023-07-27 18:01:21,957 DEBUG subiquity.models.subiquity:255 model proxy for install stage is configured, to go {'mirror', 'filesystem', 'network', 'keyboard'} 2023-07-27 18:01:21,957 DEBUG subiquity.models.subiquity:255 model mirror for install stage is configured, to go {'filesystem', 'network', 'keyboard'} 2023-07-27 18:01:21,957 DEBUG subiquity.models.subiquity:255 model ssh for postinstall stage is configured, to go {'identity', 'network', 'codecs', 'snaplist', 'timezone', 'drivers'} 2023-07-27 18:01:21,957 DEBUG subiquity.models.subiquity:255 model snaplist for postinstall stage is configured, to go {'drivers', 'codecs', 'network', 'timezone', 'identity'} 2023-07-27 18:01:21,957 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-07-27 18:01:21,960 INFO aiohttp.access:206 [27/Jul/2023:18:01:21 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22mirror%22%2C%22proxy%22%2C%22ssh%22%2C%22snaplist%22%2C%22ubuntu_pro%22%5D HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:22,108 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/bare_5.snap', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 18:01:22,133 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/core20_1974.snap', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 18:01:22,166 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/firefox_2908.snap', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 18:01:22,198 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 18:01:22,221 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 18:01:22,268 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 18:01:22,287 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1373.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 18:01:22,306 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/pc_151.snap', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 18:01:22,339 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/snap-store_959.snap', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 18:01:22,363 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1u6fluv3/root.dir/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 18:01:23,093 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 18:01:23,093 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 18:01:23,094 INFO aiohttp.access:206 [27/Jul/2023:18:01:23 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:23,095 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 2023-07-27 18:01:23,769 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None 2023-07-27 18:01:23,771 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 18:01:23,816 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 18:01:23,837 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 18:01:23,866 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 18:01:23,881 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 18:01:23,897 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 18:01:23,930 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 18:01:23,951 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 18:01:23,978 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 18:01:23,994 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 18:01:24,017 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 18:01:24,032 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1u6fluv3/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:24,036 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1u6fluv3/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:24,058 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1u6fluv3/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:24,064 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1u6fluv3/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:01:24,075 DEBUG subiquity.server.controllers.filesystem:303 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) 2023-07-27 18:01:24,075 DEBUG subiquity.server.controllers.filesystem:376 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) for variation enhanced-secureboot 2023-07-27 18:01:24,077 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"... 2023-07-27 18:01:24,077 INFO aiohttp.access:206 [27/Jul/2023:18:01:23 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 667 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:24,090 DEBUG root:37 start: subiquity/Filesystem/has_rst_GET: 2023-07-27 18:01:24,090 DEBUG root:37 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false 2023-07-27 18:01:24,091 INFO aiohttp.access:206 [27/Jul/2023:18:01:24 +0000] "GET /storage/has_rst HTTP/1.1" 200 191 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:24,091 DEBUG root:37 start: subiquity/Filesystem/has_bitlocker_GET: 2023-07-27 18:01:24,091 DEBUG root:37 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2023-07-27 18:01:24,091 INFO aiohttp.access:206 [27/Jul/2023:18:01:24 +0000] "GET /storage/has_bitlocker HTTP/1.1" 200 188 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:24,117 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 18:01:24,137 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 18:01:24,143 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 18:01:24,143 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 18:01:24,143 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 18:01:24,143 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 18:01:24,143 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 18:01:24,162 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 18:01:24,163 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 18:01:24,163 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 18:01:24,605 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 18:01:24,606 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 18:01:24,608 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 18:01:24,608 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 18:01:24,608 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 18:01:24,608 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 18:01:24,608 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 18:01:24,608 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 18:01:24,616 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 18:01:24,616 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 18:01:24,616 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 18:01:24,616 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 18:01:24,616 INFO curtin:1342 Validating extracted storage config components 2023-07-27 18:01:24,625 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 18:01:24,625 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 18:01:24,625 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 18:01:24,625 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 18:01:24,625 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 18:01:24,625 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 18:01:24,626 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.5 seconds 2023-07-27 18:01:24,626 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 18:01:52,735 DEBUG root:37 start: subiquity/Locale/POST: 2023-07-27 18:01:52,736 DEBUG subiquity.server.controllers.locale:68 en_US.UTF-8 2023-07-27 18:01:52,736 DEBUG subiquity.models.subiquity:255 model locale for postinstall stage is configured, to go {'drivers', 'codecs', 'network', 'timezone', 'identity'} 2023-07-27 18:01:52,736 DEBUG root:37 finish: subiquity/Locale/POST: SUCCESS: 200 null 2023-07-27 18:01:52,736 INFO aiohttp.access:206 [27/Jul/2023:18:01:52 +0000] "POST /locale HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:52,736 DEBUG subiquitycore.utils:96 arun_command called: ['localectl', 'set-locale', 'en_US.UTF-8'] 2023-07-27 18:01:52,762 DEBUG root:37 start: subiquity/Keyboard/GET: 2023-07-27 18:01:52,772 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-07-27 18:01:52,772 INFO aiohttp.access:206 [27/Jul/2023:18:01:52 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:52,782 DEBUG root:37 start: subiquity/Keyboard/GET: 2023-07-27 18:01:52,792 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-07-27 18:01:52,798 INFO aiohttp.access:206 [27/Jul/2023:18:01:52 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:52,798 DEBUG root:37 start: subiquity/Keyboard/input_source_POST: 2023-07-27 18:01:52,799 DEBUG subiquitycore.utils:96 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] 2023-07-27 18:01:53,372 DEBUG subiquitycore.utils:106 arun_command ['localectl', 'set-locale', 'en_US.UTF-8'] exited with code 0 2023-07-27 18:01:53,379 DEBUG subiquitycore.utils:106 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] exited with code 0 2023-07-27 18:01:53,379 DEBUG root:37 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2023-07-27 18:01:53,379 INFO aiohttp.access:206 [27/Jul/2023:18:01:52 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:55,331 DEBUG root:37 start: subiquity/Keyboard/POST: 2023-07-27 18:01:55,331 DEBUG subiquity.server.controllers.keyboard:182 KeyboardSetting(layout='us', variant='', toggle=None) 2023-07-27 18:01:55,332 DEBUG subiquity.models.subiquity:255 model keyboard for install stage is configured, to go {'filesystem', 'network'} 2023-07-27 18:01:55,332 DEBUG root:37 finish: subiquity/Keyboard/POST: SUCCESS: 200 null 2023-07-27 18:01:55,332 INFO aiohttp.access:206 [27/Jul/2023:18:01:55 +0000] "POST /keyboard HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:56,769 INFO root:37 start: subiquity/Meta/mark_configured_POST: 2023-07-27 18:01:56,769 DEBUG subiquitycore.models.network:428 has_network True 2023-07-27 18:01:56,769 DEBUG subiquity.models.subiquity:255 model network for install stage is configured, to go {'filesystem'} 2023-07-27 18:01:56,769 DEBUG subiquity.models.subiquity:255 model network for postinstall stage is configured, to go {'identity', 'codecs', 'timezone', 'drivers'} 2023-07-27 18:01:56,769 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-07-27 18:01:56,770 INFO aiohttp.access:206 [27/Jul/2023:18:01:56 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22network%22%5D HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:56,778 DEBUG root:37 start: subiquity/Source/GET: 2023-07-27 18:01:56,778 DEBUG root:37 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal... 2023-07-27 18:01:56,778 INFO aiohttp.access:206 [27/Jul/2023:18:01:56 +0000] "GET /source HTTP/1.1" 200 606 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:56,779 DEBUG root:37 start: subiquity/Drivers/GET: 2023-07-27 18:01:56,779 DEBUG root:37 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": false, "search_drivers": fa... 2023-07-27 18:01:56,779 INFO aiohttp.access:206 [27/Jul/2023:18:01:56 +0000] "GET /drivers HTTP/1.1" 200 268 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:01:56,779 DEBUG root:37 start: subiquity/Codecs/GET: 2023-07-27 18:01:56,779 DEBUG root:37 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false} 2023-07-27 18:01:56,779 INFO aiohttp.access:206 [27/Jul/2023:18:01:56 +0000] "GET /codecs HTTP/1.1" 200 205 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:02:02,257 DEBUG root:37 start: subiquity/Source/POST: 2023-07-27 18:02:02,257 DEBUG subiquity.models.subiquity:255 model source for install stage is configured, to go {'filesystem'} 2023-07-27 18:02:02,257 DEBUG subiquity.server.controllers.drivers:92 source variant has been set. Querying list of drivers. 2023-07-27 18:02:02,258 DEBUG root:37 finish: subiquity/Source/POST: SUCCESS: 200 null 2023-07-27 18:02:02,258 INFO aiohttp.access:206 [27/Jul/2023:18:02:02 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:02:02,258 DEBUG subiquity.server.controllers.filesystem:376 got system None for variation classic 2023-07-27 18:02:02,258 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp1lv1ziab/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:02,263 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmp1lv1ziab/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:02,270 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.enhanced-secureboot.squashfs', '/tmp/tmp1lv1ziab/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:02,279 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmp1lv1ziab/minimal.standard.enhanced-secureboot.squashfs.dir:/tmp/tmp1lv1ziab/minimal.standard.squashfs.dir:/tmp/tmp1lv1ziab/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp1lv1ziab/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:02,287 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/systems/enhanced-secureboot-desktop', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 18:02:02,290 ERROR root:37 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2023-07-27 18:02:02,290 ERROR root:37 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2023-07-27 18:02:02,290 DEBUG root:37 start: subiquity/Drivers/_list_drivers: 2023-07-27 18:02:02,290 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt: 2023-07-27 18:02:02,290 DEBUG root:37 start: subiquity/Drivers/POST: 2023-07-27 18:02:02,290 DEBUG subiquity.models.subiquity:255 model drivers for postinstall stage is configured, to go {'codecs', 'timezone', 'identity'} 2023-07-27 18:02:02,290 DEBUG root:37 finish: subiquity/Drivers/POST: SUCCESS: 200 null 2023-07-27 18:02:02,290 INFO aiohttp.access:206 [27/Jul/2023:18:02:02 +0000] "POST /drivers HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:02:02,291 DEBUG root:37 start: subiquity/Codecs/POST: 2023-07-27 18:02:02,291 DEBUG subiquity.models.subiquity:255 model codecs for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 18:02:02,291 DEBUG root:37 finish: subiquity/Codecs/POST: SUCCESS: 200 null 2023-07-27 18:02:02,291 INFO aiohttp.access:206 [27/Jul/2023:18:02:02 +0000] "POST /codecs HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:02:02,319 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/bare_5.snap', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 18:02:02,335 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/core20_1974.snap', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 18:02:02,371 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/firefox_2908.snap', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 18:02:02,373 DEBUG root:37 start: subiquity/Filesystem/v2_guided_GET: 2023-07-27 18:02:02,393 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 18:02:02,419 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 18:02:02,439 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 18:02:02,471 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1373.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 18:02:02,491 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/pc_151.snap', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 18:02:02,523 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/snap-store_959.snap', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 18:02:02,543 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp1lv1ziab/root.dir/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 18:02:04,007 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None 2023-07-27 18:02:04,008 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 18:02:04,060 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 18:02:04,095 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 18:02:04,114 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 18:02:04,135 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 18:02:04,155 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 18:02:04,197 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 18:02:04,211 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 18:02:04,241 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 18:02:04,258 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 18:02:04,283 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 18:02:04,316 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1lv1ziab/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:04,322 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1lv1ziab/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:04,358 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1lv1ziab/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:04,362 DEBUG curtin:92 Running command ['umount', '/tmp/tmp1lv1ziab/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 18:02:04,366 DEBUG subiquity.server.controllers.filesystem:303 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) 2023-07-27 18:02:04,366 DEBUG subiquity.server.controllers.filesystem:376 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) for variation enhanced-secureboot 2023-07-27 18:02:04,367 DEBUG root:37 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2023-07-27 18:02:04,367 INFO aiohttp.access:206 [27/Jul/2023:18:02:02 +0000] "GET /storage/v2/guided?wait=true HTTP/1.1" 200 582 "-" "Dart/2.19 (dart:io)" 2023-07-27 18:02:04,395 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 18:02:04,421 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 18:02:04,422 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 18:02:04,422 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 18:02:04,422 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 18:02:04,422 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 18:02:04,422 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 18:02:04,423 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 18:02:04,423 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 18:02:04,423 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 18:02:04,661 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 18:02:04,662 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 18:02:04,664 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 18:02:04,664 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 18:02:04,664 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 18:02:04,664 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 18:02:04,664 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 18:02:04,664 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 18:02:04,672 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 18:02:04,672 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 18:02:04,672 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 18:02:04,672 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 18:02:04,672 INFO curtin:1342 Validating extracted storage config components 2023-07-27 18:02:04,681 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 18:02:04,681 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 18:02:04,681 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 18:02:04,681 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 18:02:04,682 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 18:02:04,682 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 18:02:04,682 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.3 seconds 2023-07-27 18:02:04,682 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 18:11:30,316 ERROR root:37 finish: subiquity/Meta/status_GET: FAIL: cancelled 2023-07-27 18:29:09,519 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 18:29:09,549 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 18:29:09,556 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 18:29:09,556 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 18:29:09,556 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 18:29:09,556 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 18:29:09,556 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 18:55:44,500 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 18:55:44,537 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 18:55:44,538 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 18:55:44,538 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 18:55:44,538 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 18:55:44,538 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 18:55:44,538 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 19:04:30,927 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 19:04:30,957 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 19:04:30,958 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-07-27 19:04:31,026 DEBUG pyroute2.ndb.140336878272416.sources.localhost:74 init 2023-07-27 19:04:31,026 DEBUG pyroute2.ndb.140336878272416.sources.localhost:384 starting the source 2023-07-27 19:04:31,027 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:74 init 2023-07-27 19:04:31,027 DEBUG pyroute2.ndb.140336878272416.sources.localhost:74 connecting 2023-07-27 19:04:31,027 DEBUG pyroute2.ndb.140336878272416.sources.localhost:74 loading 2023-07-27 19:04:31,027 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:384 starting the source 2023-07-27 19:04:31,028 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:74 connecting 2023-07-27 19:04:31,029 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:74 loading 2023-07-27 19:04:31,037 DEBUG pyroute2.ndb.140336878272416.sources.localhost:74 running 2023-07-27 19:04:31,038 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:74 running 2023-07-27 19:04:31,041 DEBUG pyroute2.ndb.140336878272416.sources.localhost:399 source shutdown 2023-07-27 19:04:31,041 DEBUG pyroute2.ndb.140336878272416.sources.localhost:374 sync 2023-07-27 19:04:31,041 DEBUG pyroute2.ndb.140336878272416.sources.localhost:369 shutdown handled by the main thread 2023-07-27 19:04:31,041 DEBUG pyroute2.ndb.140336878272416.sources.localhost:74 stopped 2023-07-27 19:04:31,041 DEBUG pyroute2.ndb.140336878272416.main:851 flush DB for the target localhost 2023-07-27 19:04:31,042 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:399 source shutdown 2023-07-27 19:04:31,042 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:374 sync 2023-07-27 19:04:31,042 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-07-27 19:04:31,042 DEBUG pyroute2.ndb.140336878272416.sources.localhost/nsmanager:74 stopped 2023-07-27 19:04:31,042 DEBUG pyroute2.ndb.140336878272416.main:851 flush DB for the target localhost/nsmanager 2023-07-27 19:04:31,042 DEBUG subiquitycore.controllers.network:110 default routes False 2023-07-27 19:04:31,042 DEBUG probert.network:731 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-07-27 19:04:31,050 DEBUG pyroute2.ndb.140336878257056.sources.localhost:74 init 2023-07-27 19:04:31,050 DEBUG pyroute2.ndb.140336878257056.sources.localhost:384 starting the source 2023-07-27 19:04:31,050 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:74 init 2023-07-27 19:04:31,050 DEBUG pyroute2.ndb.140336878257056.sources.localhost:74 connecting 2023-07-27 19:04:31,050 DEBUG pyroute2.ndb.140336878257056.sources.localhost:74 loading 2023-07-27 19:04:31,051 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:384 starting the source 2023-07-27 19:04:31,051 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:74 connecting 2023-07-27 19:04:31,052 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:74 loading 2023-07-27 19:04:31,060 DEBUG pyroute2.ndb.140336878257056.sources.localhost:74 running 2023-07-27 19:04:31,063 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:74 running 2023-07-27 19:04:31,078 DEBUG pyroute2.ndb.140336878257056.sources.localhost:399 source shutdown 2023-07-27 19:04:31,078 DEBUG pyroute2.ndb.140336878257056.sources.localhost:374 sync 2023-07-27 19:04:31,078 DEBUG pyroute2.ndb.140336878257056.sources.localhost:369 shutdown handled by the main thread 2023-07-27 19:04:31,078 DEBUG pyroute2.ndb.140336878257056.sources.localhost:74 stopped 2023-07-27 19:04:31,078 DEBUG pyroute2.ndb.140336878257056.main:851 flush DB for the target localhost 2023-07-27 19:04:31,079 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:399 source shutdown 2023-07-27 19:04:31,079 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:374 sync 2023-07-27 19:04:31,079 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-07-27 19:04:31,079 DEBUG pyroute2.ndb.140336878257056.sources.localhost/nsmanager:74 stopped 2023-07-27 19:04:31,083 DEBUG pyroute2.ndb.140336878257056.main:851 flush DB for the target localhost/nsmanager 2023-07-27 19:04:31,084 DEBUG subiquitycore.controllers.network:110 default routes True 2023-07-27 19:04:31,084 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 19:04:31,087 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 19:04:31,087 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-07-27 19:04:31,097 DEBUG pyroute2.ndb.140336879240528.sources.localhost:74 init 2023-07-27 19:04:31,097 DEBUG pyroute2.ndb.140336879240528.sources.localhost:384 starting the source 2023-07-27 19:04:31,097 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:74 init 2023-07-27 19:04:31,097 DEBUG pyroute2.ndb.140336879240528.sources.localhost:74 connecting 2023-07-27 19:04:31,098 DEBUG pyroute2.ndb.140336879240528.sources.localhost:74 loading 2023-07-27 19:04:31,098 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:384 starting the source 2023-07-27 19:04:31,098 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:74 connecting 2023-07-27 19:04:31,100 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:74 loading 2023-07-27 19:04:31,109 DEBUG pyroute2.ndb.140336879240528.sources.localhost:74 running 2023-07-27 19:04:31,110 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:74 running 2023-07-27 19:04:31,111 DEBUG pyroute2.ndb.140336879240528.sources.localhost:399 source shutdown 2023-07-27 19:04:31,111 DEBUG pyroute2.ndb.140336879240528.sources.localhost:374 sync 2023-07-27 19:04:31,111 DEBUG pyroute2.ndb.140336879240528.sources.localhost:369 shutdown handled by the main thread 2023-07-27 19:04:31,111 DEBUG pyroute2.ndb.140336879240528.sources.localhost:74 stopped 2023-07-27 19:04:31,111 DEBUG pyroute2.ndb.140336879240528.main:851 flush DB for the target localhost 2023-07-27 19:04:31,112 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:399 source shutdown 2023-07-27 19:04:31,112 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:374 sync 2023-07-27 19:04:31,112 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-07-27 19:04:31,112 DEBUG pyroute2.ndb.140336879240528.sources.localhost/nsmanager:74 stopped 2023-07-27 19:04:31,112 DEBUG pyroute2.ndb.140336879240528.main:851 flush DB for the target localhost/nsmanager 2023-07-27 19:04:31,113 DEBUG subiquitycore.controllers.network:110 default routes True 2023-07-27 19:04:31,113 DEBUG probert.network:731 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2023-07-27 19:04:31,121 DEBUG pyroute2.ndb.140336874330512.sources.localhost:74 init 2023-07-27 19:04:31,122 DEBUG pyroute2.ndb.140336874330512.sources.localhost:384 starting the source 2023-07-27 19:04:31,122 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:74 init 2023-07-27 19:04:31,122 DEBUG pyroute2.ndb.140336874330512.sources.localhost:74 connecting 2023-07-27 19:04:31,122 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:384 starting the source 2023-07-27 19:04:31,122 DEBUG pyroute2.ndb.140336874330512.sources.localhost:74 loading 2023-07-27 19:04:31,123 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:74 connecting 2023-07-27 19:04:31,123 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:74 loading 2023-07-27 19:04:31,127 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:74 running 2023-07-27 19:04:31,133 DEBUG pyroute2.ndb.140336874330512.sources.localhost:74 running 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.sources.localhost:399 source shutdown 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.sources.localhost:374 sync 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.sources.localhost:369 shutdown handled by the main thread 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.sources.localhost:74 stopped 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.main:851 flush DB for the target localhost 2023-07-27 19:04:31,134 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:399 source shutdown 2023-07-27 19:04:31,135 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:374 sync 2023-07-27 19:04:31,135 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:369 shutdown handled by the main thread 2023-07-27 19:04:31,135 DEBUG pyroute2.ndb.140336874330512.sources.localhost/nsmanager:74 stopped 2023-07-27 19:04:31,135 DEBUG pyroute2.ndb.140336874330512.main:851 flush DB for the target localhost/nsmanager 2023-07-27 19:04:31,135 DEBUG subiquitycore.controllers.network:110 default routes True 2023-07-27 19:04:31,711 DEBUG subiquity.server.geoip:123 no CountryCode found in '10.172.68.114IP NOT FOUND\n' 2023-07-27 19:23:28,499 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 19:23:28,522 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 19:23:28,522 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 19:23:28,522 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 19:23:28,522 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 19:23:28,522 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 19:23:28,522 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 19:51:02,499 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 19:51:02,530 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 19:51:02,530 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 19:51:02,530 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 19:51:02,530 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 19:51:02,530 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 19:51:02,530 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 20:17:43,500 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 20:17:43,538 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 20:17:43,538 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 20:17:43,538 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 20:17:43,538 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 20:17:43,538 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 20:17:43,538 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 20:45:12,499 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 20:45:12,523 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 20:45:12,523 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 20:45:12,523 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 20:45:12,523 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 20:45:12,523 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 20:45:12,523 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 21:01:47,831 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 21:01:47,832 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 21:01:47,832 INFO aiohttp.access:206 [27/Jul/2023:21:01:47 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:47,834 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 21:01:47,835 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 21:01:47,835 INFO aiohttp.access:206 [27/Jul/2023:21:01:47 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:47,878 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 21:01:47,902 INFO root:37 start: subiquity/Meta/client_variant_POST: 2023-07-27 21:01:47,903 INFO root:37 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-07-27 21:01:47,903 INFO aiohttp.access:206 [27/Jul/2023:21:01:47 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:47,950 DEBUG root:37 start: subiquity/Source/GET: 2023-07-27 21:01:47,950 DEBUG root:37 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal... 2023-07-27 21:01:47,951 INFO aiohttp.access:206 [27/Jul/2023:21:01:47 +0000] "GET /source HTTP/1.1" 200 606 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:48,002 DEBUG root:37 start: subiquity/Source/POST: 2023-07-27 21:01:48,002 DEBUG subiquity.models.subiquity:255 model source for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:48,002 DEBUG subiquity.server.controllers.drivers:92 source variant has been set. Querying list of drivers. 2023-07-27 21:01:48,002 DEBUG root:37 finish: subiquity/Source/POST: SUCCESS: 200 null 2023-07-27 21:01:48,003 INFO aiohttp.access:206 [27/Jul/2023:21:01:48 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:48,003 DEBUG subiquity.server.controllers.filesystem:376 got system None for variation classic 2023-07-27 21:01:48,003 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp4qfr2wgi/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:48,013 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmp4qfr2wgi/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:48,016 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.enhanced-secureboot.squashfs', '/tmp/tmp4qfr2wgi/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:48,033 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmp4qfr2wgi/minimal.standard.enhanced-secureboot.squashfs.dir:/tmp/tmp4qfr2wgi/minimal.standard.squashfs.dir:/tmp/tmp4qfr2wgi/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp4qfr2wgi/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:48,039 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/systems/enhanced-secureboot-desktop', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 21:01:48,041 ERROR root:37 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2023-07-27 21:01:48,041 ERROR root:37 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2023-07-27 21:01:48,041 DEBUG root:37 start: subiquity/Drivers/_list_drivers: 2023-07-27 21:01:48,041 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt: 2023-07-27 21:01:48,041 INFO root:37 start: subiquity/Meta/mark_configured_POST: 2023-07-27 21:01:48,042 DEBUG subiquity.models.subiquity:255 model ubuntu_pro for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:48,042 DEBUG subiquity.models.subiquity:255 model proxy for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:48,042 DEBUG subiquity.models.subiquity:255 model mirror for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:48,042 DEBUG subiquity.models.subiquity:255 model ssh for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:48,042 DEBUG subiquity.models.subiquity:255 model snaplist for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:48,042 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-07-27 21:01:48,042 INFO aiohttp.access:206 [27/Jul/2023:21:01:48 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22mirror%22%2C%22proxy%22%2C%22ssh%22%2C%22snaplist%22%2C%22ubuntu_pro%22%5D HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:48,069 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/bare_5.snap', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 21:01:48,087 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/core20_1974.snap', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 21:01:48,132 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/firefox_2908.snap', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 21:01:48,164 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 21:01:48,188 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 21:01:48,224 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 21:01:48,244 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1373.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 21:01:48,265 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/pc_151.snap', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 21:01:48,287 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/snap-store_959.snap', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 21:01:48,317 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmp4qfr2wgi/root.dir/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 21:01:49,341 INFO root:37 start: subiquity/Meta/status_GET: 2023-07-27 21:01:49,342 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-07-27 21:01:49,342 INFO aiohttp.access:206 [27/Jul/2023:21:01:49 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:49,350 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 2023-07-27 21:01:49,846 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None 2023-07-27 21:01:49,848 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 21:01:49,887 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 21:01:49,912 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 21:01:49,949 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 21:01:49,966 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 21:01:49,999 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 21:01:50,029 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 21:01:50,071 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 21:01:50,090 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 21:01:50,107 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 21:01:50,134 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 21:01:50,150 DEBUG curtin:92 Running command ['umount', '/tmp/tmp4qfr2wgi/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:50,155 DEBUG curtin:92 Running command ['umount', '/tmp/tmp4qfr2wgi/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:50,179 DEBUG curtin:92 Running command ['umount', '/tmp/tmp4qfr2wgi/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:50,184 DEBUG curtin:92 Running command ['umount', '/tmp/tmp4qfr2wgi/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:50,188 DEBUG subiquity.server.controllers.filesystem:303 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) 2023-07-27 21:01:50,188 DEBUG subiquity.server.controllers.filesystem:376 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) for variation enhanced-secureboot 2023-07-27 21:01:50,189 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-vda", "label"... 2023-07-27 21:01:50,190 INFO aiohttp.access:206 [27/Jul/2023:21:01:49 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 667 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:50,192 DEBUG root:37 start: subiquity/Filesystem/has_rst_GET: 2023-07-27 21:01:50,193 DEBUG root:37 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false 2023-07-27 21:01:50,193 INFO aiohttp.access:206 [27/Jul/2023:21:01:50 +0000] "GET /storage/has_rst HTTP/1.1" 200 191 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:50,194 DEBUG root:37 start: subiquity/Filesystem/has_bitlocker_GET: 2023-07-27 21:01:50,194 DEBUG root:37 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 [] 2023-07-27 21:01:50,194 INFO aiohttp.access:206 [27/Jul/2023:21:01:50 +0000] "GET /storage/has_bitlocker HTTP/1.1" 200 188 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:50,220 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:01:50,245 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:01:50,246 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 21:01:50,246 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 21:01:50,246 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:01:50,246 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:01:50,246 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:01:50,247 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:01:50,247 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:01:50,247 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:01:50,470 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:01:50,471 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:01:50,474 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:01:50,475 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:01:50,475 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:01:50,475 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:01:50,475 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:01:50,475 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:01:50,484 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:01:50,484 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:01:50,484 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:01:50,484 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:01:50,484 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:01:50,494 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:01:50,494 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:01:50,494 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:01:50,494 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:01:50,494 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:01:50,494 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:01:50,494 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.2 seconds 2023-07-27 21:01:50,494 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 21:01:51,676 DEBUG root:37 start: subiquity/Locale/POST: 2023-07-27 21:01:51,676 DEBUG subiquity.server.controllers.locale:68 en_US.UTF-8 2023-07-27 21:01:51,676 DEBUG subiquity.models.subiquity:255 model locale for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:51,677 DEBUG root:37 finish: subiquity/Locale/POST: SUCCESS: 200 null 2023-07-27 21:01:51,677 INFO aiohttp.access:206 [27/Jul/2023:21:01:51 +0000] "POST /locale HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:51,677 DEBUG subiquitycore.utils:96 arun_command called: ['localectl', 'set-locale', 'en_US.UTF-8'] 2023-07-27 21:01:51,704 DEBUG root:37 start: subiquity/Keyboard/GET: 2023-07-27 21:01:51,727 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-07-27 21:01:51,727 INFO aiohttp.access:206 [27/Jul/2023:21:01:51 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:51,732 DEBUG root:37 start: subiquity/Keyboard/GET: 2023-07-27 21:01:51,744 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co... 2023-07-27 21:01:51,745 INFO aiohttp.access:206 [27/Jul/2023:21:01:51 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:51,770 DEBUG root:37 start: subiquity/Keyboard/input_source_POST: 2023-07-27 21:01:51,770 DEBUG subiquitycore.utils:96 arun_command called: ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] 2023-07-27 21:01:51,799 DEBUG subiquitycore.utils:106 arun_command ['localectl', 'set-locale', 'en_US.UTF-8'] exited with code 0 2023-07-27 21:01:51,857 DEBUG subiquitycore.utils:106 arun_command ['systemd-run', '--wait', '--uid=1000', '--setenv=DISPLAY=:0', '--setenv=XDG_RUNTIME_DIR=/run/user/1000', '--setenv=DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus', '--', 'gsettings', 'set', 'org.gnome.desktop.input-sources', 'sources', "[('xkb','us')]"] exited with code 0 2023-07-27 21:01:51,857 DEBUG root:37 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null 2023-07-27 21:01:51,858 INFO aiohttp.access:206 [27/Jul/2023:21:01:51 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:53,048 DEBUG root:37 start: subiquity/Keyboard/POST: 2023-07-27 21:01:53,048 DEBUG subiquity.server.controllers.keyboard:182 KeyboardSetting(layout='us', variant='', toggle=None) 2023-07-27 21:01:53,048 DEBUG subiquity.models.subiquity:255 model keyboard for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:53,048 DEBUG root:37 finish: subiquity/Keyboard/POST: SUCCESS: 200 null 2023-07-27 21:01:53,048 INFO aiohttp.access:206 [27/Jul/2023:21:01:53 +0000] "POST /keyboard HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:54,197 INFO root:37 start: subiquity/Meta/mark_configured_POST: 2023-07-27 21:01:54,197 DEBUG subiquitycore.models.network:428 has_network True 2023-07-27 21:01:54,198 DEBUG subiquity.models.subiquity:255 model network for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:54,198 DEBUG subiquity.models.subiquity:255 model network for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:54,198 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-07-27 21:01:54,198 INFO aiohttp.access:206 [27/Jul/2023:21:01:54 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22network%22%5D HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:54,215 DEBUG root:37 start: subiquity/Source/GET: 2023-07-27 21:01:54,216 DEBUG root:37 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal... 2023-07-27 21:01:54,216 INFO aiohttp.access:206 [27/Jul/2023:21:01:54 +0000] "GET /source HTTP/1.1" 200 606 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:54,218 DEBUG root:37 start: subiquity/Drivers/GET: 2023-07-27 21:01:54,219 DEBUG root:37 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": false, "search_drivers": fa... 2023-07-27 21:01:54,219 INFO aiohttp.access:206 [27/Jul/2023:21:01:54 +0000] "GET /drivers HTTP/1.1" 200 268 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:54,220 DEBUG root:37 start: subiquity/Codecs/GET: 2023-07-27 21:01:54,220 DEBUG root:37 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false} 2023-07-27 21:01:54,220 INFO aiohttp.access:206 [27/Jul/2023:21:01:54 +0000] "GET /codecs HTTP/1.1" 200 205 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:57,575 DEBUG root:37 start: subiquity/Source/POST: 2023-07-27 21:01:57,576 DEBUG subiquity.models.subiquity:255 model source for install stage is configured, to go {'filesystem'} 2023-07-27 21:01:57,576 DEBUG subiquity.server.controllers.drivers:92 source variant has been set. Querying list of drivers. 2023-07-27 21:01:57,576 DEBUG root:37 finish: subiquity/Source/POST: SUCCESS: 200 null 2023-07-27 21:01:57,576 INFO aiohttp.access:206 [27/Jul/2023:21:01:57 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:57,576 DEBUG root:37 start: subiquity/Drivers/POST: 2023-07-27 21:01:57,576 DEBUG subiquity.models.subiquity:255 model drivers for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:57,577 DEBUG root:37 finish: subiquity/Drivers/POST: SUCCESS: 200 null 2023-07-27 21:01:57,577 INFO aiohttp.access:206 [27/Jul/2023:21:01:57 +0000] "POST /drivers HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:57,577 DEBUG root:37 start: subiquity/Codecs/POST: 2023-07-27 21:01:57,577 DEBUG subiquity.models.subiquity:255 model codecs for postinstall stage is configured, to go {'timezone', 'identity'} 2023-07-27 21:01:57,577 DEBUG root:37 finish: subiquity/Codecs/POST: SUCCESS: 200 null 2023-07-27 21:01:57,577 INFO aiohttp.access:206 [27/Jul/2023:21:01:57 +0000] "POST /codecs HTTP/1.1" 200 190 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:01:57,577 DEBUG subiquity.server.controllers.filesystem:376 got system None for variation classic 2023-07-27 21:01:57,578 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmpq6mfhang/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:57,587 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmpq6mfhang/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:57,591 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.enhanced-secureboot.squashfs', '/tmp/tmpq6mfhang/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:57,605 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmpq6mfhang/minimal.standard.enhanced-secureboot.squashfs.dir:/tmp/tmpq6mfhang/minimal.standard.squashfs.dir:/tmp/tmpq6mfhang/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmpq6mfhang/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:01:57,611 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/systems/enhanced-secureboot-desktop', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 21:01:57,617 ERROR root:37 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2023-07-27 21:01:57,617 ERROR root:37 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2023-07-27 21:01:57,617 DEBUG root:37 start: subiquity/Drivers/_list_drivers: 2023-07-27 21:01:57,617 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt: 2023-07-27 21:01:57,643 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/bare_5.snap', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 21:01:57,661 DEBUG root:37 start: subiquity/Filesystem/v2_guided_GET: 2023-07-27 21:01:57,662 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/core20_1974.snap', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 21:01:57,688 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/firefox_2908.snap', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 21:01:57,708 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 21:01:57,728 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 21:01:57,762 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 21:01:57,784 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/pc-kernel_1373.snap', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 21:01:57,805 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/pc_151.snap', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 21:01:57,822 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/snap-store_959.snap', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 21:01:57,859 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'mount', '-o', 'bind', '/tmp/tmpq6mfhang/root.dir/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 21:01:59,783 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/systems/enhanced-secureboot-desktop HTTP/1.1" 200 None 2023-07-27 21:01:59,785 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snapd-desktop-integration_83.snap'] 2023-07-27 21:01:59,837 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/snap-store_959.snap'] 2023-07-27 21:01:59,865 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc_151.snap'] 2023-07-27 21:01:59,883 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/pc-kernel_1373.snap'] 2023-07-27 21:01:59,915 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gtk-common-themes_1535.snap'] 2023-07-27 21:01:59,937 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-42-2204_120.snap'] 2023-07-27 21:01:59,969 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/gnome-3-38-2004_143.snap'] 2023-07-27 21:01:59,995 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/firefox_2908.snap'] 2023-07-27 21:02:00,021 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/core20_1974.snap'] 2023-07-27 21:02:00,063 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/snaps/bare_5.snap'] 2023-07-27 21:02:00,093 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1859', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1081/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1081/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1081/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/usr/sbin:/snap/ubuntu-desktop-installer/1081/usr/bin:/snap/ubuntu-desktop-installer/1081/sbin:/snap/ubuntu-desktop-installer/1081/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-installer/1081/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1081/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1081/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1081', '--', 'umount', '/var/lib/snapd/seed/systems/enhanced-secureboot-desktop'] 2023-07-27 21:02:00,130 DEBUG curtin:92 Running command ['umount', '/tmp/tmpq6mfhang/root.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:02:00,133 DEBUG curtin:92 Running command ['umount', '/tmp/tmpq6mfhang/minimal.standard.enhanced-secureboot.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:02:00,175 DEBUG curtin:92 Running command ['umount', '/tmp/tmpq6mfhang/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:02:00,181 DEBUG curtin:92 Running command ['umount', '/tmp/tmpq6mfhang/minimal.squashfs.dir'] with allowed return codes [0] (capture=True) 2023-07-27 21:02:00,194 DEBUG subiquity.server.controllers.filesystem:303 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) 2023-07-27 21:02:00,194 DEBUG subiquity.server.controllers.filesystem:376 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=2, preserve=None)), VolumeStructure(name='ubuntu-boot', label='ubuntu-boot', offset=14930673664, 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), VolumeContent(source='shim.efi.signed', target='EFI/boot/bootx64.efi', image='', offset=None, offset_write=None, size=0, unpack=False)], update=VolumeUpdate(edition=1, preserve=None)), VolumeStructure(name='ubuntu-save', label='ubuntu-save', offset=15717105664, 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=15750660096, offset_write=None, size=4294967296, type='0FC63DAF-8483-4772-8E79-3D69D8477DE4', role='system-data', id='', filesystem='ext4', content=None, update=VolumeUpdate(edition=0, preserve=None))])}, storage_encryption=StorageEncryption(support=, storage_safety=, encryption_type=, unavailable_reason='')) for variation enhanced-secureboot 2023-07-27 21:02:00,195 DEBUG root:37 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di... 2023-07-27 21:02:00,195 INFO aiohttp.access:206 [27/Jul/2023:21:01:57 +0000] "GET /storage/v2/guided?wait=true HTTP/1.1" 200 582 "-" "Dart/2.19 (dart:io)" 2023-07-27 21:02:00,216 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:02:00,246 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:02:00,246 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 21:02:00,246 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 21:02:00,246 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:02:00,247 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:02:00,247 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:02:00,248 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:02:00,248 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:02:00,249 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:02:00,511 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:02:00,512 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:02:00,515 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:02:00,515 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:02:00,515 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:02:00,515 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:02:00,515 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:02:00,515 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:02:00,532 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:02:00,533 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:02:00,533 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:02:00,533 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:02:00,533 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:02:00,542 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:02:00,542 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:02:00,542 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:02:00,542 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:02:00,542 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:02:00,542 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:02:00,542 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.3 seconds 2023-07-27 21:02:00,542 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 21:02:18,165 ERROR root:37 finish: subiquity/Meta/status_GET: FAIL: cancelled 2023-07-27 21:12:08,500 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:12:08,541 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:12:08,542 DEBUG probert.network:585 event for addr_change: CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 21:12:08,542 DEBUG probert.network:717 addr_change CHANGE {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'192.168.122.30/24'} 2023-07-27 21:12:08,542 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp1s0 2023-07-27 21:12:08,542 DEBUG subiquity.server.controllers.network:368 dev_info enp1s0 {'dhcp4': True} 2023-07-27 21:12:08,543 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp1s0 2023-07-27 21:34:46,307 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:34:46,342 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:34:46,342 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event add Device('/sys/devices/virtual/block/loop8') 2023-07-27 21:34:46,342 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:34:46,342 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:34:46,342 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:34:46,343 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:34:46,343 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:34:46,343 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:34:46,607 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:34:46,608 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:34:46,610 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:34:46,610 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:34:46,610 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:46,611 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:46,611 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:46,611 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:46,624 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:46,624 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:34:46,624 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:46,625 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:34:46,625 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:34:46,636 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:34:46,636 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:34:46,637 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:34:46,637 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:34:46,637 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:34:46,637 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:34:46,637 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.3 seconds 2023-07-27 21:34:46,637 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 21:34:52,126 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:34:52,146 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:34:52,146 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event add Device('/sys/devices/virtual/block/loop9') 2023-07-27 21:34:52,146 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:34:52,146 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:34:52,146 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:34:52,147 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:34:52,147 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:34:52,147 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:34:52,426 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:34:52,430 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:34:52,439 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:34:52,439 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:34:52,439 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:52,439 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:52,439 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:52,439 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:52,456 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:52,456 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:34:52,456 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:52,456 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:34:52,456 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:34:52,469 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:34:52,469 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:34:52,469 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:34:52,470 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:34:52,470 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:34:52,470 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:34:52,470 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.3 seconds 2023-07-27 21:34:52,470 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 21:34:59,467 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:34:59,479 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:34:59,479 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event add Device('/sys/devices/virtual/block/loop10') 2023-07-27 21:34:59,480 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:34:59,480 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:34:59,480 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:34:59,481 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:34:59,481 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:34:59,481 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:34:59,696 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:34:59,697 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:34:59,699 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:34:59,699 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:34:59,699 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:59,699 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:59,699 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:34:59,699 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:34:59,710 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:59,710 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:34:59,710 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:34:59,710 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:34:59,710 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:34:59,722 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:34:59,722 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:34:59,722 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:34:59,723 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:34:59,723 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:34:59,723 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:34:59,723 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.2 seconds 2023-07-27 21:34:59,723 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 2023-07-27 21:35:24,563 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:35:24,606 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 1 2023-07-27 21:35:24,606 DEBUG subiquity.server.controllers.filesystem:1293 waiting 0.1 to let udev event queue settle 2023-07-27 21:35:24,707 DEBUG subiquitycore.utils:68 run_command called: ['udevadm', 'settle', '-t', '0'] 2023-07-27 21:35:24,830 DEBUG subiquitycore.utils:81 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/pci0000:00/0000:00:02.3/0000:04:00.0/virtio2/block/vda') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop1') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop4') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/pci0000:00/0000:00:1f.2/ata1/host0/target0:0:0/0:0:0:0/block/sr0') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop7') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop8') 2023-07-27 21:35:24,830 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop6') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop9') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop5') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop0') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop10') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop3') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/virtual/block/loop2') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1305 _udev_event change Device('/sys/devices/pci0000:00/0000:00:1f.2/ata1/host0/target0:0:0/0:0:0:0/block/sr0') 2023-07-27 21:35:24,831 DEBUG subiquity.server.controllers.filesystem:1288 Triggered Probert run on udev event 2023-07-27 21:35:24,831 DEBUG root:37 start: subiquity/Filesystem/_probe: 2023-07-27 21:35:24,831 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2023-07-27 21:35:24,832 DEBUG asyncio:54 Using selector: EpollSelector 2023-07-27 21:35:24,832 DEBUG probert.dasd:134 Probing DASD devies 2023-07-27 21:35:24,832 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64 2023-07-27 21:35:25,100 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-07-27 21:35:25,101 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-07-27 21:35:25,103 DEBUG curtin:1316 Extracting storage config from probe data 2023-07-27 21:35:25,103 DEBUG curtin:73 /dev/vda is multipath device member? False 2023-07-27 21:35:25,103 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:35:25,103 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:35:25,103 DEBUG curtin:86 /dev/vda is multipath device partition? False 2023-07-27 21:35:25,103 DEBUG curtin:61 /dev/vda is multipath device? False 2023-07-27 21:35:25,111 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:35:25,111 DEBUG curtin:86 /dev/sr0 is multipath device partition? False 2023-07-27 21:35:25,111 DEBUG curtin:73 /dev/sr0 is multipath device member? False 2023-07-27 21:35:25,111 DEBUG curtin:1323 Sorting extracted configurations 2023-07-27 21:35:25,112 INFO curtin:1342 Validating extracted storage config components 2023-07-27 21:35:25,120 DEBUG curtin:1359 Extracted (unmerged) storage config: storage: - id: disk-vda path: /dev/vda type: disk 2023-07-27 21:35:25,120 DEBUG curtin:1363 Generating storage config dependencies 2023-07-27 21:35:25,120 DEBUG curtin:1369 Merging storage config dependencies 2023-07-27 21:35:25,120 DEBUG curtin:1374 Merged storage config: storage: config: - id: disk-vda path: /dev/vda type: disk version: 2 2023-07-27 21:35:25,121 DEBUG subiquity.models.filesystem:1464 exclusions set() 2023-07-27 21:35:25,121 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2023-07-27 21:35:25,121 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 0.3 seconds 2023-07-27 21:35:25,121 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: