2023-11-07 16:21:58,380 INFO subiquity:199 Starting Subiquity server revision 1269 of snap /snap/ubuntu-desktop-installer/1269
2023-11-07 16:21:58,380 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1269/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1269/etc/subiquity/postinst.d']
2023-11-07 14:22:28,453 INFO root:30 start: subiquity/apply_autoinstall_config: 
2023-11-07 14:22:28,458 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2023-11-07 14:22:28,547 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:22:28,548 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-07 14:22:28,549 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-11-07 14:22:28,550 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-11-07 14:22:28,557 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-11-07 14:22:28,558 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-11-07 14:22:28,560 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:22:28,560 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-07 14:22:28,562 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-11-07 14:22:28,571 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-11-07 14:22:28,832 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-07 14:22:28,834 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-07 14:22:29,952 INFO curtin:1351 Validating extracted storage config components
2023-11-07 14:23:12,277 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:12,277 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-07 14:23:12,278 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:12,278 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-07 14:23:12,278 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:12,279 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-11-07 14:23:12,279 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-11-07 14:23:24,728 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-07 14:23:24,729 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-07 14:23:25,212 INFO curtin:1351 Validating extracted storage config components
2023-11-07 14:23:27,336 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-07 14:23:27,337 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-07 14:23:27,797 INFO curtin:1351 Validating extracted storage config components
2023-11-07 14:23:29,404 INFO curtin:1351 Validating extracted storage config components
2023-11-07 14:23:31,307 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-11-07 14:23:31,308 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-11-07 14:23:31,309 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-11-07 14:23:31,310 INFO root:30 start: subiquity/Meta/confirm_POST: 
2023-11-07 14:23:31,310 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2023-11-07 14:23:31,311 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt
2023-11-07 14:23:31,313 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:31,314 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-11-07 14:23:31,336 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:56,718 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-11-07 14:23:56,721 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-11-07 14:23:59,219 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:59,220 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-11-07 14:23:59,574 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:23:59,575 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-11-07 14:23:59,577 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-07 14:24:46,519 ERROR root:30 finish: subiquity/Install/install/configure_apt: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1832', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1269/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1269/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1269/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/usr/sbin:/snap/ubuntu-desktop-installer/1269/usr/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/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/1269/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1269', '--', '/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.1832.3"}}', 'in-target', '-t', '/tmp/tmp9qgfwdyg/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-11-07 14:24:46,557 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1699367086.519524813.install_fail.crash
2023-11-07 14:24:46,557 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1832', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1269/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1269/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1269/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/usr/sbin:/snap/ubuntu-desktop-installer/1269/usr/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/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/1269/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1269', '--', '/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.1832.3"}}', 'in-target', '-t', '/tmp/tmp9qgfwdyg/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-11-07 14:24:46,558 INFO root:30 start: subiquity/ErrorReporter/1699367086.519524813.install_fail/add_info: 
2023-11-07 14:24:46,558 ERROR subiquity.server.server:415 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1269/bin/subiquity/subiquity/server/controllers/shutdown.py", line 73, in _wait_install
    await self.app.controllers.Install.install_task
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.1832', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1269/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1269/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1269/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/usr/sbin:/snap/ubuntu-desktop-installer/1269/usr/bin:/snap/ubuntu-desktop-installer/1269/sbin:/snap/ubuntu-desktop-installer/1269/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/1269/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1269/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1269', '--', '/snap/ubuntu-desktop-installer/1269/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.1832.3"}}', 'in-target', '-t', '/tmp/tmp9qgfwdyg/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.