2023-11-22 08:31:11,847 INFO subiquity:199 Starting Subiquity server revision 1276 of snap /snap/ubuntu-desktop-installer/1276
2023-11-22 08:31:11,847 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1276/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1276/etc/subiquity/postinst.d']
2023-11-22 08:32:03,479 INFO root:30 start: subiquity/apply_autoinstall_config: 
2023-11-22 08:32:03,481 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2023-11-22 08:32:13,085 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:32:13,085 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-22 08:32:13,163 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:32:13,164 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-22 08:32:13,164 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:32:13,165 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-22 08:32:13,166 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-11-22 08:32:13,167 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-11-22 08:32:13,169 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:32:13,171 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-11-22 08:32:13,171 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-11-22 08:32:13,172 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:32:13,173 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-11-22 08:32:13,176 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-11-22 08:32:13,178 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-11-22 08:32:13,533 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-22 08:32:13,535 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-22 08:32:31,699 INFO curtin:1351 Validating extracted storage config components
2023-11-22 08:32:45,870 ERROR subiquity.server.controllers.refresh:197 checking for snap update failed
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1276/bin/subiquity/subiquity/server/controllers/refresh.py", line 195, in check_for_update
    result = await self.app.snapdapi.v2.find.GET(select="refresh")
  File "/snap/ubuntu-desktop-installer/1276/bin/subiquity/subiquity/common/api/client.py", line 47, in impl
    async with make_request(
  File "/snap/ubuntu-desktop-installer/1276/usr/lib/python3.10/contextlib.py", line 199, in __aenter__
    return await anext(self.gen)
  File "/snap/ubuntu-desktop-installer/1276/bin/subiquity/subiquity/server/snapdapi.py", line 298, in make_request
    content = await async_snapd.get(path[1:], **params)
  File "/snap/ubuntu-desktop-installer/1276/bin/subiquity/subiquitycore/snapd.py", line 209, in get
    response.raise_for_status()
  File "/snap/ubuntu-desktop-installer/1276/usr/lib/python3/dist-packages/requests/models.py", line 943, in raise_for_status
    raise HTTPError(http_error_msg, response=self)
requests.exceptions.HTTPError: 500 Server Error: Internal Server Error for url: http+unix://%2Frun%2Fsnapd.socket/v2/find?select=refresh
2023-11-22 08:42:07,027 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-11-22 08:42:34,229 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-22 08:42:34,230 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-22 08:42:36,229 INFO curtin:1351 Validating extracted storage config components
2023-11-22 08:42:37,968 ERROR root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled
2023-11-22 08:42:37,969 ERROR root:30 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled
2023-11-22 08:42:39,382 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-11-22 08:42:39,382 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-11-22 08:42:43,712 INFO curtin:1351 Validating extracted storage config components
2023-11-22 08:42:54,219 INFO curtin:1351 Validating extracted storage config components
2023-11-22 08:44:12,729 INFO curtin:1351 Validating extracted storage config components
2023-11-22 08:44:16,963 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-11-22 08:44:16,964 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-11-22 08:44:16,965 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-11-22 08:44:16,966 INFO root:30 start: subiquity/Meta/confirm_POST: 
2023-11-22 08:44:16,967 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2023-11-22 08:44:16,968 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt
2023-11-22 08:44:16,968 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:44:16,969 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-11-22 08:44:17,009 INFO root:30 start: subiquity/Meta/status_GET: 
2023-11-22 08:45:12,302 ERROR root:30 finish: subiquity/Install/install/configure_apt: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3572', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1276/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1276/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1276/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/usr/sbin:/snap/ubuntu-desktop-installer/1276/usr/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/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/1276/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1276', '--', '/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3572.3"}}', 'in-target', '-t', '/tmp/tmpf6ep6mj3/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-11-22 08:45:12,303 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1700642712.302447319.install_fail.crash
2023-11-22 08:45:12,303 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.3572', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1276/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1276/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1276/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/usr/sbin:/snap/ubuntu-desktop-installer/1276/usr/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/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/1276/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1276', '--', '/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3572.3"}}', 'in-target', '-t', '/tmp/tmpf6ep6mj3/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-11-22 08:45:12,303 INFO root:30 start: subiquity/ErrorReporter/1700642712.302447319.install_fail/add_info: 
2023-11-22 08:45:12,303 ERROR subiquity.server.server:415 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1276/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.3572', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1276/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1276/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1276/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/usr/sbin:/snap/ubuntu-desktop-installer/1276/usr/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/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/1276/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1276', '--', '/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3572.3"}}', 'in-target', '-t', '/tmp/tmpf6ep6mj3/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.
2023-11-22 08:45:12,304 ERROR subiquity.server.server:415 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1276/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.3572', '--property', 'PrivateMounts=yes', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1276/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1276/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1276/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/usr/sbin:/snap/ubuntu-desktop-installer/1276/usr/bin:/snap/ubuntu-desktop-installer/1276/sbin:/snap/ubuntu-desktop-installer/1276/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/1276/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1276/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1276', '--', '/snap/ubuntu-desktop-installer/1276/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.3572.3"}}', 'in-target', '-t', '/tmp/tmpf6ep6mj3/mount', '--', 'apt-get', 'update']' returned non-zero exit status 100.