2023-11-15 12:18:04,725 INFO subiquity:199 Starting Subiquity server revision 1272 of snap /snap/ubuntu-desktop-installer/1272 2023-11-15 12:18:04,725 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1272/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1272/etc/subiquity/postinst.d'] 2023-11-15 12:18:23,245 INFO root:30 start: subiquity/apply_autoinstall_config: 2023-11-15 12:18:23,255 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2023-11-15 12:18:32,022 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:18:32,024 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:18:32,040 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2023-11-15 12:18:32,072 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:18:32,072 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 12:18:32,136 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:18:32,137 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 12:18:32,138 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:18:32,139 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 12:18:32,139 INFO root:30 start: subiquity/Meta/client_variant_POST: 2023-11-15 12:18:32,140 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-11-15 12:18:32,142 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:18:32,144 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2023-11-15 12:18:32,144 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2023-11-15 12:18:32,145 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:18:32,145 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 12:18:32,159 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-15 12:18:32,161 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-15 12:18:37,150 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:18:57,124 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:18:57,125 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:18:58,062 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:19:50,153 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:19:50,155 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:19:51,116 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:19:52,104 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:19:52,108 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:19:53,646 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:19:55,957 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:19:55,959 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:19:56,950 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:20:42,948 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:20:42,949 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:20:44,130 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:21:36,161 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 12:21:36,162 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 12:21:37,137 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:22:24,729 INFO curtin:1351 Validating extracted storage config components 2023-11-15 12:22:32,739 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-15 12:22:32,740 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-15 12:22:32,742 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2023-11-15 12:22:32,744 INFO root:30 start: subiquity/Meta/confirm_POST: 2023-11-15 12:22:32,745 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null 2023-11-15 12:22:32,747 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt 2023-11-15 12:22:32,749 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:22:32,750 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 12:22:32,813 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 12:22:53,022 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt 2023-11-15 12:22:53,023 INFO root:30 start: subiquity/Install/install/curtin_install: installing system 2023-11-15 12:23:02,264 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin/cmd-block-meta: FAIL: configuring dm_crypt: dm_crypt-0 2023-11-15 12:23:02,265 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning/builtin: FAIL: running 'curtin block-meta simple' 2023-11-15 12:23:02,265 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step/cmd-install/stage-partitioning: FAIL: configuring storage 2023-11-15 12:23:03,586 ERROR root:30 finish: subiquity/Install/install/curtin_install/run_curtin_step: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2261', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1272/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1272/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1272/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/usr/sbin:/snap/ubuntu-desktop-installer/1272/usr/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/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/1272/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1272', '--', '/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2261.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmppmgtn6_h/mount']' returned non-zero exit status 3. 2023-11-15 12:23:03,586 ERROR root:30 finish: subiquity/Install/install/curtin_install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2261', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1272/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1272/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1272/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/usr/sbin:/snap/ubuntu-desktop-installer/1272/usr/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/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/1272/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1272', '--', '/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2261.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmppmgtn6_h/mount']' returned non-zero exit status 3. 2023-11-15 12:23:03,588 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1700050983.586962223.install_fail.crash 2023-11-15 12:23:03,588 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.2261', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1272/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1272/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1272/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/usr/sbin:/snap/ubuntu-desktop-installer/1272/usr/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/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/1272/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1272', '--', '/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2261.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmppmgtn6_h/mount']' returned non-zero exit status 3. 2023-11-15 12:23:03,589 INFO root:30 start: subiquity/ErrorReporter/1700050983.586962223.install_fail/add_info: 2023-11-15 12:23:03,589 ERROR subiquity.server.server:415 top level error Traceback (most recent call last): File "/snap/ubuntu-desktop-installer/1272/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.2261', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1272/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1272/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1272/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/usr/sbin:/snap/ubuntu-desktop-installer/1272/usr/bin:/snap/ubuntu-desktop-installer/1272/sbin:/snap/ubuntu-desktop-installer/1272/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/1272/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1272/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1272', '--', '/snap/ubuntu-desktop-installer/1272/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.2261.6"}}', '-c', '/var/log/installer/curtin-install/subiquity-partitioning.conf', 'install', '--set', 'json:stages=["partitioning"]', 'cp:///tmp/tmppmgtn6_h/mount']' returned non-zero exit status 3.