2023-06-23 07:39:08,567 INFO subiquity:163 Starting Subiquity server revision 1099 of snap /snap/ubuntu-desktop-installer/1099
2023-06-23 07:39:08,567 INFO subiquity:164 Arguments passed: ['/snap/ubuntu-desktop-installer/1099/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1099/etc/subiquity/postinst.d']
2023-06-23 07:39:08,567 DEBUG subiquity:165 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz layerfs-path=minimal.standard.live.squashfs --- quiet splash\n', _tokens={'quiet', '---', 'splash'}, _values={'BOOT_IMAGE': '/casper/vmlinuz', 'layerfs-path': 'minimal.standard.live.squashfs'})
2023-06-23 07:39:08,568 DEBUG subiquity:166 Environment: environ({'SNAP_REVISION': '1099', 'SNAP_REAL_HOME': '/root', 'SNAP_USER_COMMON': '/root/snap/ubuntu-desktop-installer/common', 'PATH_ORIG': '/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/bin/subiquity', 'SYSTEMD_EXEC_PID': '3048', 'SNAP_CONTEXT': '74bEUHFkfEzVu0OJQxw4WEXgn-9QWENJ2T4ldgQSM-M7QrNnfsoV', 'LANG': 'C.UTF-8', 'SNAP_ARCH': 'amd64', 'PY3OR2_PYTHON': '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', 'SNAP_INSTANCE_NAME': 'ubuntu-desktop-installer', 'SNAP_USER_DATA': '/root/snap/ubuntu-desktop-installer/1099', 'INVOCATION_ID': '807b7519e3ee4113befedc7f411dd6db', '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/1099/lib:/snap/ubuntu-desktop-installer/1099/usr/lib:/snap/ubuntu-desktop-installer/1099/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-installer/1099/usr/lib/x86_64-linux-gnu', 'PYTHONPATH_ORIG': '', 'PYTHONPATH': '/snap/ubuntu-desktop-installer/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', 'PYTHON': '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', 'PYTHONIOENCODING': 'utf-8', 'SNAP': '/snap/ubuntu-desktop-installer/1099', 'SNAP_COMMON': '/var/snap/ubuntu-desktop-installer/common', 'SNAP_VERSION': '0+git.01fa1247', 'DISPLAY': ':0', 'SHLVL': '1', 'SNAP_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void', 'SNAP_COOKIE': '74bEUHFkfEzVu0OJQxw4WEXgn-9QWENJ2T4ldgQSM-M7QrNnfsoV', 'SNAP_DATA': '/var/snap/ubuntu-desktop-installer/1099', 'LD_LIBRARY_PATH': '/var/lib/snapd/lib/gl:/var/lib/snapd/lib/gl32:/var/lib/snapd/void:/snap/ubuntu-desktop-installer/1099/lib:/snap/ubuntu-desktop-installer/1099/usr/lib:/snap/ubuntu-desktop-installer/1099/lib/x86_64-linux-gnu:/snap/ubuntu-desktop-installer/1099/usr/lib/x86_64-linux-gnu', 'SNAP_NAME': 'ubuntu-desktop-installer', 'JOURNAL_STREAM': '8:23470', 'PATH': '/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/bin/subiquity', 'OLDPWD': '/var/snap/ubuntu-desktop-installer/1099', '_': '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', 'APPORT_DATA_DIR': '/snap/ubuntu-desktop-installer/1099/bin/subiquity/share/apport'})
2023-06-23 07:39:08,568 DEBUG asyncio:54 Using selector: EpollSelector
2023-06-23 07:39:08,568 DEBUG subiquitycore.prober:36 Prober() init finished, data:None
2023-06-23 07:39:08,600 DEBUG curtin:92 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:08,620 DEBUG subiquitycore.utils:96 arun_command called: ['cloud-init', 'status', '--wait']
2023-06-23 07:39:09,040 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:09,040 DEBUG curtin.reporting.finish.subiquity/Meta/status_GET:45 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "CLOUD_INIT_WAIT", "confirming_tty": "", "error": null, "cloud_init...
2023-06-23 07:39:09,040 INFO aiohttp.access:206  [23/Jun/2023:07:39:09 +0000] "GET /meta/status HTTP/1.1" 200 420 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:09,738 DEBUG subiquitycore.utils:106 arun_command ['cloud-init', 'status', '--wait'] exited with code 0
2023-06-23 07:39:09,739 DEBUG subiquity.server.server:547 waited 1.1181583404541016s for cloud-init
2023-06-23 07:39:09,739 DEBUG subiquity.server.server:559 cloud-init status: '\nstatus: disabled\n', assumed disabled
2023-06-23 07:39:09,739 DEBUG subiquity.server.server:494 load_autoinstall_config only_early True file None
2023-06-23 07:39:09,739 DEBUG subiquity.server.server:494 load_autoinstall_config only_early False file None
2023-06-23 07:39:09,739 DEBUG subiquitycore.core:123 starting controllers
2023-06-23 07:39:09,740 DEBUG subiquity.server.controllers.kernel:66 Using kernel linux-generic-hwe-22.04 due to /run/kernel-meta-package
2023-06-23 07:39:09,741 DEBUG subiquity.models.source:94 loaded 2 sources from '/cdrom/casper/install-sources.yaml'
2023-06-23 07:39:09,742 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2023-06-23 07:39:09,742 DEBUG probert.network:585 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s3'}
2023-06-23 07:39:09,742 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-06-23 07:39:09,742 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'10.0.2.15/24'}
2023-06-23 07:39:09,742 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'}
2023-06-23 07:39:09,742 DEBUG probert.network:585 event for addr_change: NEW {'ifindex': 2, 'flags': 640, 'family': 10, 'scope': 253, 'local': b'fe80::525e:11e9:8862:9e35/64'}
2023-06-23 07:39:09,742 DEBUG probert.network:672 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'}
2023-06-23 07:39:09,782 DEBUG subiquitycore.models.network:436 new_link 1 lo lo
2023-06-23 07:39:09,782 DEBUG subiquitycore.models.network:438 ignoring based on type
2023-06-23 07:39:09,782 DEBUG probert.network:672 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'enp0s3'}
2023-06-23 07:39:09,831 DEBUG subiquitycore.models.network:436 new_link 2 enp0s3 eth
2023-06-23 07:39:09,831 DEBUG subiquitycore.models.network:467 new_link 2 enp0s3 with config {}
2023-06-23 07:39:09,831 DEBUG root:37 start: subiquity/Network/_send_update: NEW enp0s3
2023-06-23 07:39:09,831 DEBUG subiquity.server.controllers.network:368 dev_info enp0s3 {}
2023-06-23 07:39:09,831 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: NEW enp0s3
2023-06-23 07:39:09,831 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'}
2023-06-23 07:39:09,831 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 512, 'family': 2, 'scope': 0, 'local': b'10.0.2.15/24'}
2023-06-23 07:39:09,831 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp0s3
2023-06-23 07:39:09,831 DEBUG subiquity.server.controllers.network:368 dev_info enp0s3 {}
2023-06-23 07:39:09,831 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s3
2023-06-23 07:39:09,831 DEBUG probert.network:717 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'}
2023-06-23 07:39:09,831 DEBUG probert.network:717 addr_change NEW {'ifindex': 2, 'flags': 640, 'family': 10, 'scope': 253, 'local': b'fe80::525e:11e9:8862:9e35/64'}
2023-06-23 07:39:09,831 DEBUG root:37 start: subiquity/Network/_send_update: CHANGE enp0s3
2023-06-23 07:39:09,831 DEBUG subiquity.server.controllers.network:368 dev_info enp0s3 {}
2023-06-23 07:39:09,831 DEBUG root:37 finish: subiquity/Network/_send_update: SUCCESS: CHANGE enp0s3
2023-06-23 07:39:09,831 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2}
2023-06-23 07:39:09,846 DEBUG pyroute2.ndb.140517203191040.sources.localhost:74 init
2023-06-23 07:39:09,846 DEBUG pyroute2.ndb.140517203191040.sources.localhost:384 starting the source
2023-06-23 07:39:09,853 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:74 init
2023-06-23 07:39:09,853 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:384 starting the source
2023-06-23 07:39:09,853 DEBUG pyroute2.ndb.140517203191040.sources.localhost:74 connecting
2023-06-23 07:39:09,853 DEBUG pyroute2.ndb.140517203191040.sources.localhost:74 loading
2023-06-23 07:39:09,854 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:74 connecting
2023-06-23 07:39:09,854 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:74 loading
2023-06-23 07:39:09,866 DEBUG pyroute2.ndb.140517203191040.sources.localhost:74 running
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:74 running
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.sources.localhost:399 source shutdown
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.sources.localhost:374 sync
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.sources.localhost:369 shutdown handled by the main thread
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.sources.localhost:74 stopped
2023-06-23 07:39:09,873 DEBUG pyroute2.ndb.140517203191040.main:851 flush DB for the target localhost
2023-06-23 07:39:09,874 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:399 source shutdown
2023-06-23 07:39:09,874 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:374 sync
2023-06-23 07:39:09,874 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:369 shutdown handled by the main thread
2023-06-23 07:39:09,874 DEBUG pyroute2.ndb.140517203191040.sources.localhost/nsmanager:74 stopped
2023-06-23 07:39:09,880 DEBUG pyroute2.ndb.140517203191040.main:851 flush DB for the target localhost/nsmanager
2023-06-23 07:39:09,880 DEBUG subiquitycore.controllers.network:110 default routes True
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'10.0.2.0/24', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'169.254.0.0/16', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'10.0.2.15', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'10.0.2.255', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'::1', 'ifindex': 1}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::525e:11e9:8862:9e35', 'ifindex': 2}
2023-06-23 07:39:09,881 DEBUG probert.network:731 route_change NEW {'family': 10, 'type': 5, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2}
2023-06-23 07:39:10,025 DEBUG subiquitycore.core:126 controllers started
2023-06-23 07:39:10,025 INFO root:37 start: subiquity/apply_autoinstall_config: 
2023-06-23 07:39:10,026 DEBUG root:37 start: subiquity/Early/apply_autoinstall_config: 
2023-06-23 07:39:10,034 DEBUG root:37 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,034 DEBUG root:37 start: subiquity/Reporting/apply_autoinstall_config: 
2023-06-23 07:39:10,035 DEBUG root:37 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,035 DEBUG root:37 start: subiquity/Error/apply_autoinstall_config: 
2023-06-23 07:39:10,035 DEBUG root:37 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,035 DEBUG root:37 start: subiquity/Userdata/apply_autoinstall_config: 
2023-06-23 07:39:10,035 DEBUG root:37 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,035 DEBUG subiquity.models.subiquity:255 model userdata for postinstall stage is configured, to go {'identity', 'snaplist', 'locale', 'ubuntu_pro', 'drivers', 'ssh', 'packages', 'network'}
2023-06-23 07:39:10,035 DEBUG root:37 start: subiquity/Package/apply_autoinstall_config: 
2023-06-23 07:39:10,035 DEBUG root:37 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,035 DEBUG subiquity.models.subiquity:255 model packages for postinstall stage is configured, to go {'identity', 'snaplist', 'locale', 'ubuntu_pro', 'network', 'ssh', 'drivers'}
2023-06-23 07:39:10,035 DEBUG root:37 start: subiquity/Debconf/apply_autoinstall_config: 
2023-06-23 07:39:10,035 DEBUG root:37 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,036 DEBUG subiquity.models.subiquity:255 model debconf_selections for install stage is configured, to go {'mirror', 'source', 'kernel', 'filesystem', 'keyboard', 'proxy', 'network'}
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Locale as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Refresh as interactive
2023-06-23 07:39:10,036 DEBUG root:37 start: subiquity/Kernel/apply_autoinstall_config: 
2023-06-23 07:39:10,036 DEBUG root:37 finish: subiquity/Kernel/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,036 DEBUG subiquity.models.subiquity:255 model kernel for install stage is configured, to go {'mirror', 'source', 'filesystem', 'keyboard', 'proxy', 'network'}
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Integrity as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Keyboard as interactive
2023-06-23 07:39:10,036 DEBUG root:37 start: subiquity/Zdev/apply_autoinstall_config: 
2023-06-23 07:39:10,036 DEBUG root:37 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Source as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Network as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping UbuntuPro as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Proxy as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Mirror as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Filesystem as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Identity as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping SSH as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping SnapList as interactive
2023-06-23 07:39:10,036 DEBUG root:37 start: subiquity/Ad/apply_autoinstall_config: 
2023-06-23 07:39:10,036 DEBUG root:37 finish: subiquity/Ad/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Codecs as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Drivers as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping OEM as interactive
2023-06-23 07:39:10,036 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping TimeZone as interactive
2023-06-23 07:39:10,037 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Install as interactive
2023-06-23 07:39:10,037 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Updates as interactive
2023-06-23 07:39:10,037 DEBUG root:37 start: subiquity/Late/apply_autoinstall_config: 
2023-06-23 07:39:10,037 DEBUG root:37 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,037 DEBUG subiquity.server.server:486 apply_autoinstall_config: skipping Shutdown as interactive
2023-06-23 07:39:10,037 INFO root:37 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2023-06-23 07:39:10,037 DEBUG subiquity.models.subiquity:255 model locale for postinstall stage is configured, to go {'identity', 'snaplist', 'ubuntu_pro', 'network', 'ssh', 'drivers'}
2023-06-23 07:39:10,037 DEBUG root:37 start: subiquity/Refresh/configure_snapd: 
2023-06-23 07:39:10,037 DEBUG root:37 start: subiquity/Refresh/configure_snapd/get_details: 
2023-06-23 07:39:10,038 DEBUG root:37 start: subiquity/Refresh/check_for_update: 
2023-06-23 07:39:10,038 DEBUG subiquitycore.utils:96 arun_command called: ['/usr/sbin/realm', 'discover']
2023-06-23 07:39:10,050 DEBUG root:37 start: subiquity/OEM/load_metapackages_list: 
2023-06-23 07:39:10,050 DEBUG root:37 start: subiquity/OEM/load_metapackages_list/wait_apt: 
2023-06-23 07:39:10,050 DEBUG root:37 start: subiquity/Install/install: 
2023-06-23 07:39:10,050 DEBUG subiquity.server.controllers.snaplist:87 loading list of snaps
2023-06-23 07:39:10,131 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/snaps/ubuntu-desktop-installer HTTP/1.1" 200 1354
2023-06-23 07:39:10,152 DEBUG root:37 start: subiquity/Filesystem/_probe: 
2023-06-23 07:39:10,152 DEBUG root:37 start: subiquity/Refresh/check_for_update: 
2023-06-23 07:39:10,153 DEBUG root:37 start: subiquity/SnapList/loader: 
2023-06-23 07:39:10,170 DEBUG root:37 start: subiquity/Filesystem/_probe/probe_once: restricted=False
2023-06-23 07:39:10,178 DEBUG asyncio:54 Using selector: EpollSelector
2023-06-23 07:39:10,193 ERROR root:37 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-06-23 07:39:10,193 DEBUG root:37 start: subiquity/SnapList/loader/list: 
2023-06-23 07:39:10,193 DEBUG root:37 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '0+git.01fa1247'
2023-06-23 07:39:10,195 DEBUG subiquity.server.controllers.refresh:148 snap tracking , not resetting based on .disk/info
2023-06-23 07:39:10,195 DEBUG root:37 finish: subiquity/Refresh/configure_snapd: SUCCESS: 
2023-06-23 07:39:10,195 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:10,196 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-23 07:39:10,196 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,211 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:10,212 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-23 07:39:10,212 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,212 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:10,212 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-23 07:39:10,212 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,212 INFO root:37 start: subiquity/Meta/client_variant_POST: 
2023-06-23 07:39:10,212 INFO root:37 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-06-23 07:39:10,212 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,212 DEBUG subiquitycore.utils:106 arun_command ['/usr/sbin/realm', 'discover'] exited with code 1
2023-06-23 07:39:10,228 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:10,229 DEBUG root:37 start: subiquity/Locale/GET: 
2023-06-23 07:39:10,229 DEBUG root:37 finish: subiquity/Locale/GET: SUCCESS: 200 "C.UTF-8"
2023-06-23 07:39:10,229 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /locale HTTP/1.1" 200 195 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,229 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:10,229 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-06-23 07:39:10,229 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,239 ERROR probert.raid:40 Failed mdadm_assemble, mdadm command not found: [Errno 2] No such file or directory: 'mdadm'
2023-06-23 07:39:10,251 INFO root:37 start: subiquity/Meta/mark_configured_POST: 
2023-06-23 07:39:10,251 DEBUG subiquity.models.subiquity:255 model ubuntu_pro for postinstall stage is configured, to go {'identity', 'snaplist', 'ssh', 'network', 'drivers', 'codecs', 'timezone'}
2023-06-23 07:39:10,251 DEBUG subiquity.models.subiquity:255 model proxy for install stage is configured, to go {'mirror', 'source', 'filesystem', 'keyboard', 'network'}
2023-06-23 07:39:10,251 DEBUG subiquity.models.subiquity:255 model mirror for install stage is configured, to go {'filesystem', 'network', 'keyboard', 'source'}
2023-06-23 07:39:10,251 DEBUG subiquity.models.subiquity:255 model ssh for postinstall stage is configured, to go {'identity', 'snaplist', 'network', 'drivers', 'codecs', 'timezone'}
2023-06-23 07:39:10,251 DEBUG subiquity.models.subiquity:255 model snaplist for postinstall stage is configured, to go {'identity', 'network', 'drivers', 'codecs', 'timezone'}
2023-06-23 07:39:10,251 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-06-23 07:39:10,251 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +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/3.0 (dart:io)"
2023-06-23 07:39:10,274 INFO root:37 start: subiquity/Meta/interactive_sections_GET: 
2023-06-23 07:39:10,274 INFO root:37 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-06-23 07:39:10,275 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/interactive_sections HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:10,783 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None
2023-06-23 07:39:10,785 DEBUG root:37 finish: subiquity/SnapList/loader/list: SUCCESS: 
2023-06-23 07:39:10,785 DEBUG subiquity.server.controllers.snaplist:100 fetched list of 23 snaps
2023-06-23 07:39:10,785 DEBUG root:37 start: subiquity/SnapList/loader/fetch/microk8s: 
2023-06-23 07:39:11,006 DEBUG probert.dasd:134 Probing DASD devies
2023-06-23 07:39:11,006 DEBUG probert.dasd:137 DASD devices only present on s390x, arch=x86_64
2023-06-23 07:39:11,039 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 760
2023-06-23 07:39:11,042 DEBUG subiquity.server.controllers.refresh:211 check_for_update received [Snap(id='amcUKQILKXHHTlmSa7NMdnXSx02dNeeT', name='core22', status=<SnapStatus.AVAILABLE: 'available'>, version='20230612', revision='766', channel='latest/stable', publisher=Publisher(id='canonical', username='canonical', display_name='Canonical'))]
2023-06-23 07:39:11,042 DEBUG root:37 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available
2023-06-23 07:39:11,857 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None
2023-06-23 07:39:11,879 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 
2023-06-23 07:39:11,879 DEBUG root:37 start: subiquity/SnapList/loader/fetch/nextcloud: 
2023-06-23 07:39:12,060 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-06-23 07:39:12,064 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-06-23 07:39:12,081 DEBUG probert.utils:85 Command `/usr/sbin/dumpe2fs -h /dev/sda3` exited with result: 0
2023-06-23 07:39:12,082 DEBUG probert.utils:48 stdout: ------------------------------------------
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem volume name:   <none>
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Last mounted on:          /
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem UUID:          5aa583e2-bfbc-450d-a046-8d8040264d24
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem magic number:  0xEF53
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem revision #:    1 (dynamic)
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem features:      has_journal ext_attr resize_inode dir_index orphan_file filetype extent 64bit flex_bg sparse_super large_file huge_file dir_nlink extra_isize metadata_csum
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem flags:         signed_directory_hash 
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Default mount options:    user_xattr acl
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem state:         clean
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Errors behavior:          Continue
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem OS type:       Linux
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Inode count:              1605632
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Block count:              6421504
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Reserved block count:     321075
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Overhead clusters:        144831
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Free blocks:              3241493
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Free inodes:              1407158
2023-06-23 07:39:12,082 DEBUG probert.utils:50 First block:              0
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Block size:               4096
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Fragment size:            4096
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Group descriptor size:    64
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Reserved GDT blocks:      1024
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Blocks per group:         32768
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Fragments per group:      32768
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Inodes per group:         8192
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Inode blocks per group:   512
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Flex block group size:    16
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Filesystem created:       Wed Jun 21 16:16:26 2023
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Last mount time:          Wed Jun 21 16:20:25 2023
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Last write time:          Wed Jun 21 16:20:25 2023
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Mount count:              2
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Maximum mount count:      -1
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Last checked:             Wed Jun 21 16:16:26 2023
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Check interval:           0 (<none>)
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Lifetime writes:          16 GB
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Reserved blocks uid:      0 (user root)
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Reserved blocks gid:      0 (group root)
2023-06-23 07:39:12,082 DEBUG probert.utils:50 First inode:              11
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Inode size:	          256
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Required extra isize:     32
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Desired extra isize:      32
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Journal inode:            8
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Default directory hash:   half_md4
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Directory Hash Seed:      f768caa9-14e3-4060-a7a6-d60b47096d9e
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Journal backup:           inode blocks
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Checksum type:            crc32c
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Checksum:                 0x9f08cf86
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Orphan file inode:        12
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Journal features:         journal_incompat_revoke journal_64bit journal_checksum_v3
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Total journal size:       128M
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Total journal blocks:     32768
2023-06-23 07:39:12,082 DEBUG probert.utils:50 Max transaction length:   32768
2023-06-23 07:39:12,083 DEBUG probert.utils:50 Fast commit length:       0
2023-06-23 07:39:12,083 DEBUG probert.utils:50 Journal sequence:         0x00000eae
2023-06-23 07:39:12,083 DEBUG probert.utils:50 Journal start:            0
2023-06-23 07:39:12,083 DEBUG probert.utils:50 Journal checksum type:    crc32c
2023-06-23 07:39:12,083 DEBUG probert.utils:50 Journal checksum:         0x24357e39
2023-06-23 07:39:12,083 DEBUG probert.utils:50 
2023-06-23 07:39:12,083 DEBUG probert.utils:48 stderr: ------------------------------------------
2023-06-23 07:39:12,083 DEBUG probert.utils:50 dumpe2fs 1.47.0 (5-Feb-2023)
2023-06-23 07:39:12,083 DEBUG probert.utils:88 --------------------------------------------------
2023-06-23 07:39:12,115 DEBUG probert.utils:85 Command `/usr/sbin/resize2fs -P /dev/sda3` exited with result: 0
2023-06-23 07:39:12,115 DEBUG probert.utils:48 stdout: ------------------------------------------
2023-06-23 07:39:12,116 DEBUG probert.utils:50 Estimated minimum size of the filesystem: 3357442
2023-06-23 07:39:12,116 DEBUG probert.utils:48 stderr: ------------------------------------------
2023-06-23 07:39:12,116 DEBUG probert.utils:50 resize2fs 1.47.0 (5-Feb-2023)
2023-06-23 07:39:12,116 DEBUG probert.utils:88 --------------------------------------------------
2023-06-23 07:39:12,247 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None
2023-06-23 07:39:12,248 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 
2023-06-23 07:39:12,248 DEBUG root:37 start: subiquity/SnapList/loader/fetch/wekan: 
2023-06-23 07:39:12,614 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None
2023-06-23 07:39:12,615 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 
2023-06-23 07:39:12,615 DEBUG root:37 start: subiquity/SnapList/loader/fetch/kata-containers: 
2023-06-23 07:39:12,845 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None
2023-06-23 07:39:12,846 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 
2023-06-23 07:39:12,846 DEBUG root:37 start: subiquity/SnapList/loader/fetch/docker: 
2023-06-23 07:39:12,863 DEBUG curtin:1316 Extracting storage config from probe data
2023-06-23 07:39:12,863 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2023-06-23 07:39:12,864 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2023-06-23 07:39:12,864 DEBUG curtin:73 /dev/sda is multipath device member? False
2023-06-23 07:39:12,864 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-23 07:39:12,864 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-23 07:39:12,864 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-23 07:39:12,864 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-23 07:39:12,879 DEBUG curtin:73 /dev/sda1 is multipath device member? False
2023-06-23 07:39:12,879 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:12,879 DEBUG curtin:61 /dev/sda1 is multipath device? False
2023-06-23 07:39:12,879 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:12,879 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:12,884 DEBUG curtin:73 /dev/sda2 is multipath device member? False
2023-06-23 07:39:12,884 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:12,884 DEBUG curtin:61 /dev/sda2 is multipath device? False
2023-06-23 07:39:12,884 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:12,884 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:12,896 DEBUG curtin:73 /dev/sda3 is multipath device member? False
2023-06-23 07:39:12,896 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:12,896 DEBUG curtin:61 /dev/sda3 is multipath device? False
2023-06-23 07:39:12,896 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:12,896 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:12,910 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2023-06-23 07:39:12,910 DEBUG curtin:73 /dev/sda2 is multipath device member? False
2023-06-23 07:39:12,918 DEBUG curtin:73 /dev/sda3 is multipath device member? False
2023-06-23 07:39:12,927 DEBUG curtin:1323 Sorting extracted configurations
2023-06-23 07:39:12,927 INFO curtin:1342 Validating extracted storage config components
2023-06-23 07:39:12,944 DEBUG curtin:1359 Extracted (unmerged) storage config:
storage:
-   id: disk-sda
    path: /dev/sda
    ptable: gpt
    serial: VBOX_HARDDISK_VBac6c4918-f2893723
    type: disk
-   device: disk-sda
    flag: bios_grub
    id: partition-sda1
    number: 1
    offset: 1048576
    partition_type: 21686148-6449-6e6f-744e-656564454649
    path: /dev/sda1
    size: 1048576
    type: partition
-   device: disk-sda
    flag: boot
    id: partition-sda2
    number: 2
    offset: 2097152
    partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
    path: /dev/sda2
    size: 537919488
    type: partition
-   device: disk-sda
    flag: linux
    id: partition-sda3
    number: 3
    offset: 540016640
    partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
    path: /dev/sda3
    size: 26302480384
    type: partition
-   fstype: vfat
    id: format-partition-sda2
    type: format
    volume: partition-sda2
-   fstype: ext4
    id: format-partition-sda3
    type: format
    uuid: 5aa583e2-bfbc-450d-a046-8d8040264d24
    volume: partition-sda3

2023-06-23 07:39:12,944 DEBUG curtin:1363 Generating storage config dependencies
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'lvm_partition', 'partition', 'dm_crypt', 'raid', 'bcache', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: format-partition-sda3:SourceType:format -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'lvm_partition', 'partition', 'dm_crypt', 'raid', 'bcache', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:238 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:12,944 DEBUG curtin:1369 Merging storage config dependencies
2023-06-23 07:39:12,945 DEBUG curtin:1374 Merged storage config:
storage:
    config:
    -   id: disk-sda
        path: /dev/sda
        ptable: gpt
        serial: VBOX_HARDDISK_VBac6c4918-f2893723
        type: disk
    -   device: disk-sda
        flag: bios_grub
        id: partition-sda1
        number: 1
        offset: 1048576
        partition_type: 21686148-6449-6e6f-744e-656564454649
        path: /dev/sda1
        size: 1048576
        type: partition
    -   device: disk-sda
        flag: boot
        id: partition-sda2
        number: 2
        offset: 2097152
        partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
        path: /dev/sda2
        size: 537919488
        type: partition
    -   device: disk-sda
        flag: linux
        id: partition-sda3
        number: 3
        offset: 540016640
        partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
        path: /dev/sda3
        size: 26302480384
        type: partition
    -   fstype: vfat
        id: format-partition-sda2
        type: format
        volume: partition-sda2
    -   fstype: ext4
        id: format-partition-sda3
        type: format
        uuid: 5aa583e2-bfbc-450d-a046-8d8040264d24
        volume: partition-sda3
    version: 2

2023-06-23 07:39:12,945 DEBUG subiquity.models.filesystem:1464 exclusions set()
2023-06-23 07:39:12,945 DEBUG root:37 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False
2023-06-23 07:39:12,945 DEBUG subiquity.server.controllers.filesystem:1138 block probing took 2.8 seconds
2023-06-23 07:39:12,946 DEBUG root:37 finish: subiquity/Filesystem/_probe: SUCCESS: 
2023-06-23 07:39:13,042 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None
2023-06-23 07:39:13,043 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 
2023-06-23 07:39:13,043 DEBUG root:37 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 
2023-06-23 07:39:13,260 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 None
2023-06-23 07:39:13,261 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 
2023-06-23 07:39:13,261 DEBUG root:37 start: subiquity/SnapList/loader/fetch/rocketchat-server: 
2023-06-23 07:39:13,491 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None
2023-06-23 07:39:13,491 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 
2023-06-23 07:39:13,491 DEBUG root:37 start: subiquity/SnapList/loader/fetch/mosquitto: 
2023-06-23 07:39:13,682 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None
2023-06-23 07:39:13,683 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 
2023-06-23 07:39:13,683 DEBUG root:37 start: subiquity/SnapList/loader/fetch/etcd: 
2023-06-23 07:39:13,973 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None
2023-06-23 07:39:13,973 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 
2023-06-23 07:39:13,973 DEBUG root:37 start: subiquity/SnapList/loader/fetch/powershell: 
2023-06-23 07:39:14,194 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None
2023-06-23 07:39:14,194 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 
2023-06-23 07:39:14,194 DEBUG root:37 start: subiquity/SnapList/loader/fetch/stress-ng: 
2023-06-23 07:39:14,547 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=stress-ng HTTP/1.1" 200 None
2023-06-23 07:39:14,548 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/stress-ng: SUCCESS: 
2023-06-23 07:39:14,548 DEBUG root:37 start: subiquity/SnapList/loader/fetch/sabnzbd: 
2023-06-23 07:39:14,811 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None
2023-06-23 07:39:14,811 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 
2023-06-23 07:39:14,811 DEBUG root:37 start: subiquity/SnapList/loader/fetch/wormhole: 
2023-06-23 07:39:15,026 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None
2023-06-23 07:39:15,026 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 
2023-06-23 07:39:15,026 DEBUG root:37 start: subiquity/SnapList/loader/fetch/aws-cli: 
2023-06-23 07:39:15,248 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 None
2023-06-23 07:39:15,248 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 
2023-06-23 07:39:15,248 DEBUG root:37 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 
2023-06-23 07:39:15,464 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1799
2023-06-23 07:39:15,464 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 
2023-06-23 07:39:15,465 DEBUG root:37 start: subiquity/SnapList/loader/fetch/slcli: 
2023-06-23 07:39:15,618 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 None
2023-06-23 07:39:15,619 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 
2023-06-23 07:39:15,619 DEBUG root:37 start: subiquity/SnapList/loader/fetch/doctl: 
2023-06-23 07:39:15,824 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 None
2023-06-23 07:39:15,825 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 
2023-06-23 07:39:15,825 DEBUG root:37 start: subiquity/SnapList/loader/fetch/conjure-up: 
2023-06-23 07:39:15,997 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None
2023-06-23 07:39:15,998 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 
2023-06-23 07:39:15,998 DEBUG root:37 start: subiquity/SnapList/loader/fetch/postgresql10: 
2023-06-23 07:39:16,183 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1523
2023-06-23 07:39:16,183 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 
2023-06-23 07:39:16,183 DEBUG root:37 start: subiquity/SnapList/loader/fetch/heroku: 
2023-06-23 07:39:16,366 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None
2023-06-23 07:39:16,367 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 
2023-06-23 07:39:16,367 DEBUG root:37 start: subiquity/SnapList/loader/fetch/keepalived: 
2023-06-23 07:39:16,529 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None
2023-06-23 07:39:16,529 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 
2023-06-23 07:39:16,529 DEBUG root:37 start: subiquity/SnapList/loader/fetch/prometheus: 
2023-06-23 07:39:16,720 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None
2023-06-23 07:39:16,721 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 
2023-06-23 07:39:16,721 DEBUG root:37 start: subiquity/SnapList/loader/fetch/juju: 
2023-06-23 07:39:17,423 DEBUG urllib3.connectionpool:452 http://localhost:None "GET /v2/find?name=juju HTTP/1.1" 200 None
2023-06-23 07:39:17,424 DEBUG root:37 finish: subiquity/SnapList/loader/fetch/juju: SUCCESS: 
2023-06-23 07:39:17,424 DEBUG root:37 finish: subiquity/SnapList/loader: SUCCESS: 
2023-06-23 07:39:17,678 DEBUG root:37 start: subiquity/Locale/POST: 
2023-06-23 07:39:17,679 DEBUG subiquity.server.controllers.locale:68 en_US.UTF-8
2023-06-23 07:39:17,679 DEBUG subiquity.models.subiquity:255 model locale for postinstall stage is configured, to go {'identity', 'network', 'drivers', 'codecs', 'timezone'}
2023-06-23 07:39:17,679 DEBUG root:37 finish: subiquity/Locale/POST: SUCCESS: 200 null
2023-06-23 07:39:17,679 INFO aiohttp.access:206  [23/Jun/2023:07:39:17 +0000] "POST /locale HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:17,679 DEBUG subiquitycore.utils:96 arun_command called: ['localectl', 'set-locale', 'en_US.UTF-8']
2023-06-23 07:39:18,564 DEBUG subiquitycore.utils:106 arun_command ['localectl', 'set-locale', 'en_US.UTF-8'] exited with code 0
2023-06-23 07:39:22,301 DEBUG root:37 start: subiquity/Filesystem/has_rst_GET: 
2023-06-23 07:39:22,301 DEBUG root:37 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false
2023-06-23 07:39:22,301 INFO aiohttp.access:206  [23/Jun/2023:07:39:22 +0000] "GET /storage/has_rst HTTP/1.1" 200 191 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:22,302 DEBUG root:37 start: subiquity/Keyboard/GET: 
2023-06-23 07:39:22,307 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co...
2023-06-23 07:39:22,309 INFO aiohttp.access:206  [23/Jun/2023:07:39:22 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:22,309 DEBUG root:37 start: subiquity/Keyboard/GET: 
2023-06-23 07:39:22,313 DEBUG root:37 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"setting": {"layout": "us", "variant": "", "toggle": null}, "layouts": [{"co...
2023-06-23 07:39:22,313 INFO aiohttp.access:206  [23/Jun/2023:07:39:22 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:22,315 DEBUG root:37 start: subiquity/Keyboard/input_source_POST: 
2023-06-23 07:39:22,316 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-06-23 07:39:22,421 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-06-23 07:39:22,422 DEBUG root:37 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null
2023-06-23 07:39:22,422 INFO aiohttp.access:206  [23/Jun/2023:07:39:22 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:27,512 DEBUG root:37 start: subiquity/Keyboard/input_source_POST: 
2023-06-23 07:39:27,514 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','is')]"]
2023-06-23 07:39:27,639 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','is')]"] exited with code 0
2023-06-23 07:39:27,639 DEBUG root:37 finish: subiquity/Keyboard/input_source_POST: SUCCESS: 200 null
2023-06-23 07:39:27,639 INFO aiohttp.access:206  [23/Jun/2023:07:39:27 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:28,934 DEBUG root:37 start: subiquity/Keyboard/POST: 
2023-06-23 07:39:28,935 DEBUG subiquity.server.controllers.keyboard:182 KeyboardSetting(layout='is', variant='', toggle=None)
2023-06-23 07:39:28,936 DEBUG subiquitycore.utils:96 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only']
2023-06-23 07:39:29,300 DEBUG subiquitycore.utils:106 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0
2023-06-23 07:39:29,300 DEBUG subiquitycore.utils:96 arun_command called: ['/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin/subiquity-loadkeys']
2023-06-23 07:39:29,308 DEBUG subiquitycore.utils:106 arun_command ['/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin/subiquity-loadkeys'] exited with code 0
2023-06-23 07:39:29,308 DEBUG subiquity.models.subiquity:255 model keyboard for install stage is configured, to go {'filesystem', 'network', 'source'}
2023-06-23 07:39:29,308 DEBUG root:37 finish: subiquity/Keyboard/POST: SUCCESS: 200 null
2023-06-23 07:39:29,309 INFO aiohttp.access:206  [23/Jun/2023:07:39:28 +0000] "POST /keyboard HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:30,741 INFO root:37 start: subiquity/Meta/mark_configured_POST: 
2023-06-23 07:39:30,741 DEBUG subiquitycore.models.network:428 has_network True
2023-06-23 07:39:30,742 DEBUG subiquity.models.subiquity:255 model network for install stage is configured, to go {'filesystem', 'source'}
2023-06-23 07:39:30,742 DEBUG subiquity.models.subiquity:255 model network for postinstall stage is configured, to go {'timezone', 'codecs', 'identity', 'drivers'}
2023-06-23 07:39:30,742 INFO root:37 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-06-23 07:39:30,743 INFO aiohttp.access:206  [23/Jun/2023:07:39:30 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22network%22%5D HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:30,749 DEBUG root:37 start: subiquity/Source/GET: 
2023-06-23 07:39:30,750 DEBUG root:37 finish: subiquity/Source/GET: SUCCESS: 200 {"sources": [{"name": "Ubuntu Desktop (minimized)", "description": "A minimal...
2023-06-23 07:39:30,750 INFO aiohttp.access:206  [23/Jun/2023:07:39:30 +0000] "GET /source HTTP/1.1" 200 606 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:30,755 DEBUG root:37 start: subiquity/Drivers/GET: 
2023-06-23 07:39:30,755 DEBUG root:37 finish: subiquity/Drivers/GET: SUCCESS: 200 {"install": false, "drivers": null, "local_only": false, "search_drivers": fa...
2023-06-23 07:39:30,756 INFO aiohttp.access:206  [23/Jun/2023:07:39:30 +0000] "GET /drivers HTTP/1.1" 200 268 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:30,761 DEBUG root:37 start: subiquity/Codecs/GET: 
2023-06-23 07:39:30,761 DEBUG root:37 finish: subiquity/Codecs/GET: SUCCESS: 200 {"install": false}
2023-06-23 07:39:30,761 INFO aiohttp.access:206  [23/Jun/2023:07:39:30 +0000] "GET /codecs HTTP/1.1" 200 205 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,692 DEBUG root:37 start: subiquity/Source/POST: 
2023-06-23 07:39:31,693 DEBUG subiquity.models.subiquity:255 model source for install stage is configured, to go {'filesystem'}
2023-06-23 07:39:31,694 DEBUG subiquity.server.controllers.drivers:92 source variant has been set. Querying list of drivers.
2023-06-23 07:39:31,694 DEBUG root:37 finish: subiquity/Source/POST: SUCCESS: 200 null
2023-06-23 07:39:31,694 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,695 DEBUG subiquity.server.controllers.filesystem:376 got system None for variation default
2023-06-23 07:39:31,695 DEBUG root:37 start: subiquity/Drivers/_list_drivers: 
2023-06-23 07:39:31,695 DEBUG root:37 start: subiquity/Drivers/_list_drivers/wait_apt: 
2023-06-23 07:39:31,698 DEBUG root:37 start: subiquity/Drivers/POST: 
2023-06-23 07:39:31,698 DEBUG subiquity.models.subiquity:255 model drivers for postinstall stage is configured, to go {'codecs', 'identity', 'timezone'}
2023-06-23 07:39:31,698 DEBUG root:37 finish: subiquity/Drivers/POST: SUCCESS: 200 null
2023-06-23 07:39:31,699 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "POST /drivers HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,699 DEBUG root:37 start: subiquity/Codecs/POST: 
2023-06-23 07:39:31,699 DEBUG subiquity.models.subiquity:255 model codecs for postinstall stage is configured, to go {'identity', 'timezone'}
2023-06-23 07:39:31,699 DEBUG root:37 finish: subiquity/Codecs/POST: SUCCESS: 200 null
2023-06-23 07:39:31,702 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "POST /codecs HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,702 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 
2023-06-23 07:39:31,708 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:31,708 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 1707 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,734 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 
2023-06-23 07:39:31,735 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:31,735 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 1707 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,737 DEBUG root:37 start: subiquity/Filesystem/v2_guided_GET: 
2023-06-23 07:39:31,738 DEBUG root:37 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di...
2023-06-23 07:39:31,738 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "GET /storage/v2/guided?wait=true HTTP/1.1" 200 554 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:31,757 DEBUG root:37 start: subiquity/Filesystem/has_bitlocker_GET: 
2023-06-23 07:39:31,757 DEBUG root:37 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 []
2023-06-23 07:39:31,757 INFO aiohttp.access:206  [23/Jun/2023:07:39:31 +0000] "GET /storage/has_bitlocker HTTP/1.1" 200 188 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:32,589 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 
2023-06-23 07:39:32,592 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:32,592 INFO aiohttp.access:206  [23/Jun/2023:07:39:32 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 1707 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:32,595 DEBUG root:37 start: subiquity/Filesystem/v2_guided_GET: 
2023-06-23 07:39:32,597 DEBUG root:37 finish: subiquity/Filesystem/v2_guided_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": null, "targets": [{"di...
2023-06-23 07:39:32,597 INFO aiohttp.access:206  [23/Jun/2023:07:39:32 +0000] "GET /storage/v2/guided?wait=true HTTP/1.1" 200 554 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:34,382 DEBUG root:37 start: subiquity/Filesystem/v2_guided_POST: 
2023-06-23 07:39:34,384 DEBUG subiquity.server.controllers.filesystem:994 GuidedChoiceV2(target=GuidedStorageTargetReformat(disk_id='disk-sda', allowed=[], disallowed=[GuidedDisallowedCapability(capability=<GuidedCapability.DIRECT: 1>, reason=<GuidedDisallowedCapabilityReason.TOO_SMALL: 1>, message=None), GuidedDisallowedCapability(capability=<GuidedCapability.LVM: 2>, reason=<GuidedDisallowedCapabilityReason.TOO_SMALL: 1>, message=None), GuidedDisallowedCapability(capability=<GuidedCapability.LVM_LUKS: 3>, reason=<GuidedDisallowedCapabilityReason.TOO_SMALL: 1>, message=None)]), capability=<GuidedCapability.DIRECT: 1>, sizing_policy=<SizingPolicy.ALL: 2>, reset_partition=False)
2023-06-23 07:39:34,386 DEBUG subiquity.models.filesystem:1742 adding ext4 to Partition(device=disk-sda, size=26840399872, wipe='superblock', number=2, grub_device=None, offset=2097152, id='partition-1')
2023-06-23 07:39:34,391 DEBUG root:37 finish: subiquity/Filesystem/v2_guided_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "configured": {"target": {"disk_id":...
2023-06-23 07:39:34,391 INFO aiohttp.access:206  [23/Jun/2023:07:39:34 +0000] "POST /storage/v2/guided HTTP/1.1" 200 945 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:34,397 DEBUG root:37 start: subiquity/Filesystem/v2_POST: 
2023-06-23 07:39:34,397 DEBUG subiquity.models.subiquity:255 model filesystem for install stage is configured, to go set()
2023-06-23 07:39:34,397 DEBUG root:37 finish: subiquity/Filesystem/v2_POST: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:34,397 INFO aiohttp.access:206  [23/Jun/2023:07:39:34 +0000] "POST /storage/v2 HTTP/1.1" 200 1251 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:34,398 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-06-23 07:39:34,398 INFO aiohttp.access:206  [23/Jun/2023:07:39:10 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 423 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:34,401 DEBUG root:37 start: subiquity/Filesystem/v2_GET: 
2023-06-23 07:39:34,401 DEBUG root:37 finish: subiquity/Filesystem/v2_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:34,401 INFO aiohttp.access:206  [23/Jun/2023:07:39:34 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 1251 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:34,402 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:34,410 DEBUG root:37 start: subiquity/Filesystem/v2_orig_config_GET: 
2023-06-23 07:39:34,410 DEBUG curtin:1316 Extracting storage config from probe data
2023-06-23 07:39:34,410 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2023-06-23 07:39:34,410 DEBUG curtin:86 /dev/sr0 is multipath device partition? False
2023-06-23 07:39:34,410 DEBUG curtin:73 /dev/sda is multipath device member? False
2023-06-23 07:39:34,410 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-23 07:39:34,410 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-23 07:39:34,410 DEBUG curtin:86 /dev/sda is multipath device partition? False
2023-06-23 07:39:34,410 DEBUG curtin:61 /dev/sda is multipath device? False
2023-06-23 07:39:34,416 DEBUG curtin:73 /dev/sda1 is multipath device member? False
2023-06-23 07:39:34,416 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:34,416 DEBUG curtin:61 /dev/sda1 is multipath device? False
2023-06-23 07:39:34,416 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:34,416 DEBUG curtin:86 /dev/sda1 is multipath device partition? False
2023-06-23 07:39:34,421 DEBUG curtin:73 /dev/sda2 is multipath device member? False
2023-06-23 07:39:34,421 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:34,421 DEBUG curtin:61 /dev/sda2 is multipath device? False
2023-06-23 07:39:34,421 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:34,421 DEBUG curtin:86 /dev/sda2 is multipath device partition? False
2023-06-23 07:39:34,426 DEBUG curtin:73 /dev/sda3 is multipath device member? False
2023-06-23 07:39:34,426 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:34,426 DEBUG curtin:61 /dev/sda3 is multipath device? False
2023-06-23 07:39:34,426 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:34,426 DEBUG curtin:86 /dev/sda3 is multipath device partition? False
2023-06-23 07:39:34,444 DEBUG curtin:73 /dev/sr0 is multipath device member? False
2023-06-23 07:39:34,444 DEBUG curtin:73 /dev/sda2 is multipath device member? False
2023-06-23 07:39:34,469 DEBUG curtin:73 /dev/sda3 is multipath device member? False
2023-06-23 07:39:34,482 DEBUG curtin:1323 Sorting extracted configurations
2023-06-23 07:39:34,482 INFO curtin:1342 Validating extracted storage config components
2023-06-23 07:39:34,488 DEBUG curtin:1359 Extracted (unmerged) storage config:
storage:
-   id: disk-sda
    path: /dev/sda
    ptable: gpt
    serial: VBOX_HARDDISK_VBac6c4918-f2893723
    type: disk
-   device: disk-sda
    flag: bios_grub
    id: partition-sda1
    number: 1
    offset: 1048576
    partition_type: 21686148-6449-6e6f-744e-656564454649
    path: /dev/sda1
    size: 1048576
    type: partition
-   device: disk-sda
    flag: boot
    id: partition-sda2
    number: 2
    offset: 2097152
    partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
    path: /dev/sda2
    size: 537919488
    type: partition
-   device: disk-sda
    flag: linux
    id: partition-sda3
    number: 3
    offset: 540016640
    partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
    path: /dev/sda3
    size: 26302480384
    type: partition
-   fstype: vfat
    id: format-partition-sda2
    type: format
    volume: partition-sda2
-   fstype: ext4
    id: format-partition-sda3
    type: format
    uuid: 5aa583e2-bfbc-450d-a046-8d8040264d24
    volume: partition-sda3

2023-06-23 07:39:34,488 DEBUG curtin:1363 Generating storage config dependencies
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'lvm_partition', 'partition', 'dm_crypt', 'raid', 'bcache', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: format-partition-sda3:SourceType:format -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'lvm_partition', 'partition', 'dm_crypt', 'raid', 'bcache', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:238 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'raid', 'bcache', 'partition', 'disk'} ? result=True
2023-06-23 07:39:34,489 DEBUG curtin:1369 Merging storage config dependencies
2023-06-23 07:39:34,490 DEBUG curtin:1374 Merged storage config:
storage:
    config:
    -   id: disk-sda
        path: /dev/sda
        ptable: gpt
        serial: VBOX_HARDDISK_VBac6c4918-f2893723
        type: disk
    -   device: disk-sda
        flag: bios_grub
        id: partition-sda1
        number: 1
        offset: 1048576
        partition_type: 21686148-6449-6e6f-744e-656564454649
        path: /dev/sda1
        size: 1048576
        type: partition
    -   device: disk-sda
        flag: boot
        id: partition-sda2
        number: 2
        offset: 2097152
        partition_type: c12a7328-f81f-11d2-ba4b-00a0c93ec93b
        path: /dev/sda2
        size: 537919488
        type: partition
    -   device: disk-sda
        flag: linux
        id: partition-sda3
        number: 3
        offset: 540016640
        partition_type: 0fc63daf-8483-4772-8e79-3d69d8477de4
        path: /dev/sda3
        size: 26302480384
        type: partition
    -   fstype: vfat
        id: format-partition-sda2
        type: format
        volume: partition-sda2
    -   fstype: ext4
        id: format-partition-sda3
        type: format
        uuid: 5aa583e2-bfbc-450d-a046-8d8040264d24
        volume: partition-sda3
    version: 2

2023-06-23 07:39:34,490 DEBUG subiquity.models.filesystem:1464 exclusions set()
2023-06-23 07:39:34,490 DEBUG root:37 finish: subiquity/Filesystem/v2_orig_config_GET: SUCCESS: 200 {"status": "DONE", "error_report": null, "disks": [{"id": "disk-sda", "label"...
2023-06-23 07:39:34,491 INFO aiohttp.access:206  [23/Jun/2023:07:39:34 +0000] "GET /storage/v2/orig_config HTTP/1.1" 200 1707 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:35,767 DEBUG root:37 start: subiquity/TimeZone/GET: 
2023-06-23 07:39:35,768 DEBUG root:37 finish: subiquity/TimeZone/GET: SUCCESS: 200 {"timezone": "Atlantic/Reykjavik", "from_geoip": true}
2023-06-23 07:39:35,769 INFO aiohttp.access:206  [23/Jun/2023:07:39:35 +0000] "GET /timezone HTTP/1.1" 200 241 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:35,979 INFO root:37 start: subiquity/Meta/confirm_POST: 
2023-06-23 07:39:35,979 INFO root:37 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2023-06-23 07:39:35,980 INFO aiohttp.access:206  [23/Jun/2023:07:39:35 +0000] "POST /meta/confirm?tty=%22%2Fdev%2Ftty1%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:35,980 DEBUG subiquity.models.subiquity:255 model ssh for postinstall stage is configured, to go {'identity', 'timezone'}
2023-06-23 07:39:35,980 DEBUG subiquity.models.subiquity:255 model snaplist for postinstall stage is configured, to go {'identity', 'timezone'}
2023-06-23 07:39:35,980 INFO root:37 start: subiquity/Install/install/configure_apt: configuring apt
2023-06-23 07:39:35,980 DEBUG root:37 start: subiquity/Mirror/waiting: 
2023-06-23 07:39:35,981 INFO root:37 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-06-23 07:39:35,981 INFO aiohttp.access:206  [23/Jun/2023:07:39:34 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:35,981 DEBUG root:37 finish: subiquity/Mirror/waiting: SUCCESS: 
2023-06-23 07:39:35,981 DEBUG subiquity.server.controllers.mirror:203 Iterating over country-mirror
2023-06-23 07:39:35,981 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp7mxrfjqk/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:35,989 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmp7mxrfjqk/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:35,996 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.en.squashfs', '/tmp/tmp7mxrfjqk/minimal.standard.en.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:36,006 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmp7mxrfjqk/minimal.standard.en.squashfs.dir:/tmp/tmp7mxrfjqk/minimal.standard.squashfs.dir:/tmp/tmp7mxrfjqk/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp7mxrfjqk/root.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:36,009 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', 'mount', '-o', 'lowerdir=/tmp/tmp7mxrfjqk/root.dir,upperdir=/tmp/tmp4meknmz3/upper,workdir=/tmp/tmp4meknmz3/work', '-t', 'overlay', 'overlay', '/tmp/tmp4meknmz3/mount']
2023-06-23 07:39:36,036 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3061.1"}}', '-c', '/var/log/installer/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmp4meknmz3/mount']
2023-06-23 07:39:36,046 INFO root:37 start: subiquity/Meta/status_GET: 
2023-06-23 07:39:37,000 DEBUG subiquitycore.utils:121 astart_command called: ['apt-get', 'update', '-oAPT::Update::Error-Mode=any', '-oAPT::Sandbox::User=root', '-oAcquire::IndexTargets::deb::Packages::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::Translations::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-small::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::DEP-11-icons-large-hidpi::DefaultEnabled=false', '-oAcquire::IndexTargets::deb::CNF::DefaultEnabled=false', '-oAcquire::IndexTargets::deb-src::Sources::DefaultEnabled=false']
2023-06-23 07:39:37,030 DEBUG root:37 start: subiquity/TimeZone/POST: 
2023-06-23 07:39:37,203 DEBUG subiquity.models.subiquity:255 model timezone for postinstall stage is configured, to go {'identity'}
2023-06-23 07:39:37,203 DEBUG root:37 finish: subiquity/TimeZone/POST: SUCCESS: 200 null
2023-06-23 07:39:37,203 INFO aiohttp.access:206  [23/Jun/2023:07:39:37 +0000] "POST /timezone?tz=%22Atlantic%2FReykjavik%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:37,203 DEBUG root:37 start: subiquity/Identity/GET: 
2023-06-23 07:39:37,203 DEBUG root:37 finish: subiquity/Identity/GET: SUCCESS: 200 {"realname": "", "username": "", "crypted_password": "", "hostname": ""}
2023-06-23 07:39:37,203 INFO aiohttp.access:206  [23/Jun/2023:07:39:37 +0000] "GET /identity HTTP/1.1" 200 259 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:37,214 DEBUG root:37 start: subiquity/Ad/has_support_GET: 
2023-06-23 07:39:37,214 DEBUG root:37 finish: subiquity/Ad/has_support_GET: SUCCESS: 200 true
2023-06-23 07:39:37,215 INFO aiohttp.access:206  [23/Jun/2023:07:39:37 +0000] "GET /active_directory/has_support HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:177 Mirror checking successful
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:179 APT output follows
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Get:1 https://is.archive.ubuntu.com/ubuntu mantic InRelease [255 kB]
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Hit:2 https://is.archive.ubuntu.com/ubuntu mantic-updates InRelease
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Get:3 https://is.archive.ubuntu.com/ubuntu mantic-backports InRelease [90.7 kB]
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Hit:4 https://is.archive.ubuntu.com/ubuntu mantic-security InRelease
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Fetched 346 kB in 1s (371 kB/s)
2023-06-23 07:39:38,702 DEBUG subiquity.server.controllers.mirror:181 Reading package lists...
2023-06-23 07:39:38,703 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.squashfs', '/tmp/tmp2xvf16hd/minimal.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:38,711 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.squashfs', '/tmp/tmp2xvf16hd/minimal.standard.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:38,718 DEBUG curtin:92 Running command ['mount', '-o', 'loop,ro', '///cdrom/casper/minimal.standard.en.squashfs', '/tmp/tmp2xvf16hd/minimal.standard.en.squashfs.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:38,725 DEBUG curtin:92 Running command ['mount', '-o', 'lowerdir=/tmp/tmp2xvf16hd/minimal.standard.en.squashfs.dir:/tmp/tmp2xvf16hd/minimal.standard.squashfs.dir:/tmp/tmp2xvf16hd/minimal.squashfs.dir', '-t', 'overlay', 'overlay', '/tmp/tmp2xvf16hd/root.dir'] with allowed return codes [0] (capture=True)
2023-06-23 07:39:38,732 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', 'mount', '-o', 'lowerdir=/tmp/tmp2xvf16hd/root.dir,upperdir=/tmp/tmpt1esqete/upper,workdir=/tmp/tmpt1esqete/work', '-t', 'overlay', 'overlay', '/tmp/tmpt1esqete/mount']
2023-06-23 07:39:38,753 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3061.2"}}', '-c', '/var/log/installer/subiquity-curtin-apt.conf', 'apt-config', '-t', '/tmp/tmpt1esqete/mount']
2023-06-23 07:39:39,238 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', 'mount', '-o', 'lowerdir=/tmp/tmpt1esqete/upper:/tmp/tmp2xvf16hd/root.dir,upperdir=/tmp/tmp9aazssxb/upper,workdir=/tmp/tmp9aazssxb/work', '-t', 'overlay', 'overlay', '/tmp/tmp9aazssxb/mount']
2023-06-23 07:39:39,251 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', 'mount', '-o', 'bind', '/cdrom', '/tmp/tmp9aazssxb/mount/cdrom']
2023-06-23 07:39:39,264 DEBUG subiquitycore.utils:121 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3061.3"}}', 'in-target', '-t', '/tmp/tmp9aazssxb/mount', '--', 'apt-get', 'update']
2023-06-23 07:39:40,074 DEBUG root:37 start: subiquity/Identity/validate_username_GET: 
2023-06-23 07:39:40,074 DEBUG root:37 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2023-06-23 07:39:40,074 INFO aiohttp.access:206  [23/Jun/2023:07:39:40 +0000] "GET /identity/validate_username?username=%22u%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:40,664 DEBUG root:37 start: subiquity/Identity/validate_username_GET: 
2023-06-23 07:39:40,665 DEBUG root:37 finish: subiquity/Identity/validate_username_GET: SUCCESS: 200 "OK"
2023-06-23 07:39:40,665 INFO aiohttp.access:206  [23/Jun/2023:07:39:40 +0000] "GET /identity/validate_username?username=%22um%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-06-23 07:39:41,483 ERROR root:37 finish: subiquity/Install/install/configure_apt: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3061.3"}}', 'in-target', '-t', '/tmp/tmp9aazssxb/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-06-23 07:39:41,483 DEBUG subiquity.common.errorreport:404 generating crash report
2023-06-23 07:39:41,490 INFO subiquity.common.errorreport:426 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1687505981.483444929.install_fail.crash
2023-06-23 07:39:41,490 ERROR root:37 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3061', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1099/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1099/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1099/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/usr/sbin:/snap/ubuntu-desktop-installer/1099/usr/bin:/snap/ubuntu-desktop-installer/1099/sbin:/snap/ubuntu-desktop-installer/1099/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/1099/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1099/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1099', '--', '/snap/ubuntu-desktop-installer/1099/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3061.3"}}', 'in-target', '-t', '/tmp/tmp9aazssxb/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-06-23 07:39:41,490 INFO root:37 start: subiquity/ErrorReporter/1687505981.483444929.install_fail/add_info: