2023-11-15 11:01:19,132 INFO subiquity:199 Starting Subiquity server revision 1272 of snap /snap/ubuntu-desktop-installer/1272 2023-11-15 11:01:19,132 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 11:01:40,996 INFO root:30 start: subiquity/apply_autoinstall_config: 2023-11-15 11:01:40,998 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2023-11-15 11:01:51,927 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 11:01:51,928 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 11:01:52,029 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2023-11-15 11:01:59,479 INFO curtin:1351 Validating extracted storage config components 2023-11-15 11:02:07,047 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:02:07,047 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 11:02:07,056 INFO root:30 start: subiquity/Meta/client_variant_POST: 2023-11-15 11:02:07,057 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-11-15 11:02:07,228 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:02:07,228 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 11:02:07,229 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:02:07,229 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 11:02:07,229 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:02:07,229 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-11-15 11:02:07,229 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:02:07,230 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2023-11-15 11:02:07,230 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2023-11-15 11:02:07,230 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-15 11:02:07,231 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-15 11:02:41,949 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-11-15 11:02:41,950 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-11-15 11:02:44,391 INFO curtin:1351 Validating extracted storage config components 2023-11-15 11:02:47,183 ERROR root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2023-11-15 11:02:47,183 ERROR root:30 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2023-11-15 11:03:22,644 INFO curtin:1351 Validating extracted storage config components 2023-11-15 11:03:45,266 INFO curtin:1351 Validating extracted storage config components 2023-11-15 11:03:47,905 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-15 11:03:47,905 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-15 11:03:47,906 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2023-11-15 11:03:47,906 INFO root:30 start: subiquity/Meta/confirm_POST: 2023-11-15 11:03:47,907 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null 2023-11-15 11:03:47,907 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt 2023-11-15 11:03:47,908 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:03:47,908 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 11:03:47,928 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:04:04,118 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt 2023-11-15 11:04:04,118 INFO root:30 start: subiquity/Install/install/curtin_install: installing system 2023-11-15 11:04:38,143 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-11-15 11:04:38,144 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-11-15 11:04:39,615 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:04:39,616 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 11:04:39,652 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:04:39,652 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 11:04:39,859 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:07:05,447 INFO root:30 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system 2023-11-15 11:07:05,448 INFO root:30 start: subiquity/Install/install/postinstall: final system configuration 2023-11-15 11:07:05,457 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 11:07:05,458 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2023-11-15 11:07:05,471 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:07:05,472 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:07:29,159 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_... 2023-11-15 11:07:29,159 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_... 2023-11-15 11:07:29,242 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:07:29,242 INFO root:30 start: subiquity/Meta/status_GET: 2023-11-15 11:07:32,906 ERROR root:30 finish: subiquity/Install/install/postinstall/restore_apt_config: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3030', '--property', 'PrivateMounts=yes', '--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.3030.17"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-15 11:07:32,906 ERROR root:30 finish: subiquity/Install/install/postinstall: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3030', '--property', 'PrivateMounts=yes', '--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.3030.17"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-15 11:07:32,936 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1700046452.906576157.install_fail.crash 2023-11-15 11:07:32,936 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3030', '--property', 'PrivateMounts=yes', '--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.3030.17"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-15 11:07:32,936 INFO root:30 start: subiquity/ErrorReporter/1700046452.906576157.install_fail/add_info: 2023-11-15 11:07:32,937 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.3030', '--property', 'PrivateMounts=yes', '--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.3030.17"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-15 11:07:32,937 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.3030', '--property', 'PrivateMounts=yes', '--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.3030.17"}}', 'in-target', '-t', '/target', '--', 'apt-get', 'update']' returned non-zero exit status 100. 2023-11-15 11:07:32,938 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL... 2023-11-15 11:07:32,938 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "ERROR", "confirming_tty": "/dev/tty1", "error": {"state": "INCOMPL...