2023-10-14 10:44:14,648 INFO subiquity:199 Starting Subiquity server revision 1267 of snap /snap/ubuntu-desktop-installer/1267
2023-10-14 10:44:14,648 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1267/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1267/etc/subiquity/postinst.d']
2023-10-14 10:44:33,295 INFO root:30 start: subiquity/apply_autoinstall_config: 
2023-10-14 10:44:33,305 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2023-10-14 10:44:41,166 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-10-14 10:44:41,176 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:44:41,176 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:44:41,180 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:44:41,249 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:44:41,258 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:44:41,259 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:44:41,260 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-10-14 10:44:41,261 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-10-14 10:44:41,266 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:44:41,268 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-10-14 10:44:41,268 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-10-14 10:44:41,269 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:44:41,270 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:44:41,275 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-10-14 10:44:41,277 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-10-14 10:44:41,599 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:44:41,603 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:44:45,356 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:44:55,425 ERROR root:30 finish: subiquity/Meta/status_GET: FAIL: cancelled
2023-10-14 10:44:56,298 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:44:56,301 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:44:57,047 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:46:02,959 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:46:02,961 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:46:03,640 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:46:27,273 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:46:27,274 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:46:27,914 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:48:07,911 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:48:07,914 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:48:08,059 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:48:08,060 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:48:08,066 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-10-14 10:48:08,066 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-10-14 10:48:08,095 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:48:08,097 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:48:08,102 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:48:08,102 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:48:08,104 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:48:08,104 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-10-14 10:48:08,105 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:48:08,106 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-10-14 10:48:08,107 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-10-14 10:48:08,109 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-10-14 10:48:08,111 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-10-14 10:48:08,612 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:48:30,757 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:48:30,759 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:48:31,417 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:48:34,606 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:48:34,608 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:48:35,191 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:48:46,734 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:49:01,579 INFO subiquity.server.controllers.filesystem:1035 Resetting Filesystem model
2023-10-14 10:49:01,979 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:49:12,729 ERROR root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled
2023-10-14 10:49:12,730 ERROR root:30 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled
2023-10-14 10:49:16,804 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:49:16,806 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:49:17,438 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:49:28,722 ERROR root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled
2023-10-14 10:49:28,722 ERROR root:30 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled
2023-10-14 10:49:32,979 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-10-14 10:49:32,981 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-10-14 10:49:33,564 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:49:40,989 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:51:59,622 INFO curtin:1351 Validating extracted storage config components
2023-10-14 10:52:01,957 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-10-14 10:52:01,958 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-10-14 10:52:01,960 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-10-14 10:52:01,962 INFO root:30 start: subiquity/Meta/confirm_POST: 
2023-10-14 10:52:01,965 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2023-10-14 10:52:01,967 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt
2023-10-14 10:52:01,969 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:52:01,970 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-10-14 10:52:02,029 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:52:23,841 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-10-14 10:52:23,842 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-10-14 10:52:25,584 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:52:25,585 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-10-14 10:52:26,052 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:52:26,052 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-10-14 10:52:26,092 INFO root:30 start: subiquity/Meta/status_GET: 
2023-10-14 10:52:32,452 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt
2023-10-14 10:52:32,453 INFO root:30 start: subiquity/Install/install/curtin_install: installing system
2023-10-14 10:52:53,202 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: FAIL: removing previous storage devices
2023-10-14 10:52:53,202 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: curtin command block-meta
2023-10-14 10:52:53,203 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: FAIL: running 'curtin block-meta simple'
2023-10-14 10:52:53,204 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: FAIL: configuring storage
2023-10-14 10:52:54,549 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2266', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1267/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1267/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1267/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/usr/sbin:/snap/ubuntu-desktop-installer/1267/usr/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/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/1267/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1267', '--', '/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2266.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmptkaelt3a/mount']' returned non-zero exit status 3.
2023-10-14 10:52:54,550 ERROR root:30 finish: subiquity/Install/install/curtin_install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2266', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1267/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1267/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1267/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/usr/sbin:/snap/ubuntu-desktop-installer/1267/usr/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/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/1267/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1267', '--', '/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2266.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmptkaelt3a/mount']' returned non-zero exit status 3.
2023-10-14 10:52:54,552 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1697280774.550621271.install_fail.crash
2023-10-14 10:52:54,552 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2266', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1267/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1267/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1267/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/usr/sbin:/snap/ubuntu-desktop-installer/1267/usr/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/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/1267/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1267', '--', '/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2266.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmptkaelt3a/mount']' returned non-zero exit status 3.
2023-10-14 10:52:54,553 INFO root:30 start: subiquity/ErrorReporter/1697280774.550621271.install_fail/add_info: 
2023-10-14 10:52:54,555 ERROR subiquity.server.server:415 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1267/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.2266', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1267/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1267/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1267/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/usr/sbin:/snap/ubuntu-desktop-installer/1267/usr/bin:/snap/ubuntu-desktop-installer/1267/sbin:/snap/ubuntu-desktop-installer/1267/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/1267/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1267/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1267', '--', '/snap/ubuntu-desktop-installer/1267/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2266.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmptkaelt3a/mount']' returned non-zero exit status 3.