2022-08-29 11:58:01,189 INFO subiquity:152 Starting Subiquity server revision 607
2022-08-29 11:58:01,189 INFO subiquity:153 Arguments passed: ['/snap/ubuntu-desktop-installer/607/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/607/etc/subiquity/postinst.d', '--autoinstall=']
2022-08-29 11:58:01,189 DEBUG subiquity:154 Kernel commandline: CommandLineParams(_raw='BOOT_IMAGE=/casper/vmlinuz layerfs-path=minimal.standard.live.squashfs maybe-ubiquity --- quiet splash\n', _tokens={'splash', 'quiet', '---', 'maybe-ubiquity'}, _values={'BOOT_IMAGE': '/casper/vmlinuz', 'layerfs-path': 'minimal.standard.live.squashfs'})
2022-08-29 11:58:01,189 DEBUG subiquity:155 Storage version: 2
2022-08-29 11:58:01,189 DEBUG asyncio:59 Using selector: EpollSelector
2022-08-29 11:58:01,189 DEBUG subiquitycore.prober:34 Prober() init finished, data:None
2022-08-29 11:58:01,202 DEBUG curtin:87 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True)
2022-08-29 11:58:01,210 DEBUG subiquitycore.utils:92 arun_command called: ['cloud-init', 'status', '--wait']
2022-08-29 11:58:01,520 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2022-08-29 11:58:01,521 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...
2022-08-29 11:58:01,521 INFO aiohttp.access:233  [29/Aug/2022:11:58:01 +0000] "GET /meta/status HTTP/1.1" 200 419 "-" "Dart/2.17 (dart:io)"
2022-08-29 11:58:01,522 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2022-08-29 11:58:01,522 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...
2022-08-29 11:58:01,522 INFO aiohttp.access:233  [29/Aug/2022:11:58:01 +0000] "GET /meta/status HTTP/1.1" 200 419 "-" "Dart/2.17 (dart:io)"
2022-08-29 11:58:01,523 DEBUG curtin.reporting.start.subiquity/Meta/status_GET:45 start: subiquity/Meta/status_GET: 
2022-08-29 11:58:01,524 DEBUG curtin.reporting.start.subiquity/Meta/client_variant_POST:45 start: subiquity/Meta/client_variant_POST: 
2022-08-29 11:58:01,524 DEBUG curtin:1015 unknown type for url 'cp:///media/filesystem', assuming type 'tgz'
2022-08-29 11:58:01,524 DEBUG subiquity.models.subiquity:219 model source for install stage is configured, to go {'keyboard', 'network', 'debconf_selections', 'kernel', 'mirror', 'proxy', 'filesystem'}
2022-08-29 11:58:01,524 DEBUG curtin.reporting.finish.subiquity/Meta/client_variant_POST:45 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2022-08-29 11:58:01,524 INFO aiohttp.access:233  [29/Aug/2022:11:58:01 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 189 "-" "Dart/2.17 (dart:io)"
2022-08-29 11:58:01,525 DEBUG curtin.reporting.start.subiquity/Meta/mark_configured_POST:45 start: subiquity/Meta/mark_configured_POST: 
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model ubuntu_pro for postinstall stage is configured, to go {'timezone', 'locale', 'drivers', 'network', 'identity', 'snaplist', 'ssh', 'userdata', 'packages'}
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model proxy for install stage is configured, to go {'keyboard', 'network', 'debconf_selections', 'kernel', 'mirror', 'filesystem'}
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model mirror for install stage is configured, to go {'keyboard', 'network', 'debconf_selections', 'kernel', 'filesystem'}
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model ssh for postinstall stage is configured, to go {'timezone', 'locale', 'drivers', 'network', 'identity', 'snaplist', 'userdata', 'packages'}
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model snaplist for postinstall stage is configured, to go {'timezone', 'locale', 'drivers', 'network', 'identity', 'userdata', 'packages'}
2022-08-29 11:58:01,525 DEBUG subiquity.models.subiquity:219 model drivers for postinstall stage is configured, to go {'timezone', 'locale', 'network', 'identity', 'userdata', 'packages'}
2022-08-29 11:58:01,525 DEBUG curtin.reporting.finish.subiquity/Meta/mark_configured_POST:45 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2022-08-29 11:58:01,525 DEBUG subiquitycore.utils:114 astart_command called: ['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2682', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/607/bin/subiquity/bin:/snap/ubuntu-desktop-installer/607/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/607/bin:/snap/ubuntu-desktop-installer/607/sbin:/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/607/lib/python3.8/site-packages:/snap/ubuntu-desktop-installer/607/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/607/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/607/usr/bin/python3.8', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/607', '--', 'mount', '-o', 'lowerdir=/media/filesystem,upperdir=/tmp/tmpdzgxhkrl/upper,workdir=/tmp/tmpdzgxhkrl/work', '-t', 'overlay', 'overlay', '/tmp/tmpdzgxhkrl/mount']
2022-08-29 11:58:01,535 INFO aiohttp.access:233  [29/Aug/2022:11:58:01 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22drivers%22,%22mirror%22,%22proxy%22,%22ssh%22,%22snaplist%22,%22ubuntu_pro%22%5D HTTP/1.1" 200 189 "-" "Dart/2.17 (dart:io)"
2022-08-29 11:58:01,539 DEBUG curtin.reporting.start.subiquity/Filesystem/v2_GET:45 start: subiquity/Filesystem/v2_GET: 
2022-08-29 11:58:01,539 DEBUG curtin.reporting.finish.subiquity/Filesystem/v2_GET:45 finish: subiquity/Filesystem/v2_GET: SUCCESS: 500 Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/607...
2022-08-29 11:58:01,539 DEBUG subiquity.server.server:455 request to /storage/v2?wait=true crashed
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/607/bin/subiquity/subiquity/common/api/server.py", line 122, in handler
    result = await implementation(**args)
  File "/snap/ubuntu-desktop-installer/607/bin/subiquity/subiquity/server/controllers/filesystem.py", line 403, in v2_GET
    return await self.get_v2_storage_response(self.model, wait)
  File "/snap/ubuntu-desktop-installer/607/bin/subiquity/subiquity/server/controllers/filesystem.py", line 389, in get_v2_storage_response
    probe_resp = await self._probe_response(wait, StorageResponseV2)
  File "/snap/ubuntu-desktop-installer/607/bin/subiquity/subiquity/server/controllers/filesystem.py", line 267, in _probe_response
    await self._start_task
AttributeError: 'FilesystemController' object has no attribute '_start_task'
2022-08-29 11:58:01,539 DEBUG subiquity.common.errorreport:384 generating crash report
2022-08-29 11:58:01,540 INFO subiquity.common.errorreport:406 saving crash report 'request to /storage/v2?wait=true crashed with AttributeError' to /var/crash/1661774281.539776087.server_request_fail.crash
2022-08-29 11:58:01,540 DEBUG curtin.reporting.start.subiquity/Filesystem/has_rst_GET:45 start: subiquity/Filesystem/has_rst_GET: 
2022-08-29 11:58:01,540 DEBUG curtin.reporting.finish.subiquity/Filesystem/has_rst_GET:45 finish: subiquity/Filesystem/has_rst_GET: SUCCESS: 200 false
2022-08-29 11:58:01,540 DEBUG curtin.reporting.start.subiquity/Filesystem/has_bitlocker_GET:45 start: subiquity/Filesystem/has_bitlocker_GET: 
2022-08-29 11:58:01,540 DEBUG curtin.reporting.finish.subiquity/Filesystem/has_bitlocker_GET:45 finish: subiquity/Filesystem/has_bitlocker_GET: SUCCESS: 200 []
2022-08-29 11:58:01,540 DEBUG curtin.reporting.start.subiquity/ErrorReporter/1661774281.539776087.server_request_fail/add_info:45 start: subiquity/ErrorReporter/1661774281.539776087.server_request_fail/add_info: