2024-02-20 18:18:06,765 INFO subiquity:200 Starting Subiquity server revision 6 of snap /snap/ubuntu-desktop-bootstrap/6 of version 0+git.15cde5fb 2024-02-20 18:18:06,765 INFO subiquity:204 Arguments passed: ['/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-bootstrap/6/etc/subiquity/postinst.d'] 2024-02-20 18:18:58,266 INFO root:30 start: subiquity/apply_autoinstall_config: 2024-02-20 18:18:58,273 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2024-02-20 18:19:09,335 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-02-20 18:19:09,337 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-02-20 18:19:36,253 ERROR subiquity.server.controllers.refresh:197 checking for snap update failed Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/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-bootstrap/6/bin/subiquity/subiquity/common/api/client.py", line 47, in impl async with make_request( File "/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3.10/contextlib.py", line 199, in __aenter__ return await anext(self.gen) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/snapdapi.py", line 298, in make_request content = await async_snapd.get(path[1:], **params) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/snapd.py", line 209, in get response.raise_for_status() File "/snap/ubuntu-desktop-bootstrap/6/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 2024-02-20 18:19:45,016 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-20 18:19:45,016 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-20 18:19:45,289 INFO root:30 start: subiquity/Meta/client_variant_POST: 2024-02-20 18:19:45,289 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2024-02-20 18:19:45,296 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-20 18:19:45,297 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-20 18:19:45,318 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2024-02-20 18:19:45,319 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-02-20 18:19:48,550 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2024-02-20 18:19:49,695 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-20 18:19:49,696 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-20 18:19:49,696 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-20 18:19:49,697 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2024-02-20 18:19:49,698 INFO root:30 start: subiquity/Meta/status_GET: 2024-02-20 18:19:49,699 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2024-02-20 18:19:49,700 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2024-02-20 18:20:24,661 ERROR root:30 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2024-02-20 18:20:24,661 ERROR root:30 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2024-02-20 18:20:33,754 ERROR root:30 finish: subiquity/Filesystem/_probe/probe_once: FAIL: cancelled 2024-02-20 18:20:33,755 ERROR block-discover:1288 block probing failed restricted=False Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/context.py", line 149, in decorated_async return await meth(self, **kw) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1250, in _probe_once storage = await self.app.prober.get_storage(probe_types) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/prober.py", line 66, in get_storage return await run_in_thread(run_probert, probe_types) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/async_helpers.py", line 59, in run_in_thread return await loop.run_in_executor(None, func, *args) asyncio.exceptions.CancelledError During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3.10/asyncio/tasks.py", line 456, in wait_for return fut.result() asyncio.exceptions.CancelledError The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1282, in _probe await asyncio.wait_for(self._probe_once_task.task, 90.0) File "/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3.10/asyncio/tasks.py", line 458, in wait_for raise exceptions.TimeoutError() from exc asyncio.exceptions.TimeoutError 2024-02-20 18:20:33,763 INFO subiquity.common.errorreport:424 saving crash report 'block probing crashed with TimeoutError' to /var/crash/1708453233.762281895.block_probe_fail.crash 2024-02-20 18:20:33,765 INFO root:30 start: subiquity/ErrorReporter/1708453233.762281895.block_probe_fail/add_info: 2024-02-20 18:20:36,463 INFO root:30 finish: subiquity/ErrorReporter/1708453233.762281895.block_probe_fail/add_info: SUCCESS: written to /var/crash/1708453233.762281895.block_probe_fail.crash 2024-02-20 18:21:01,447 WARNING curtin:426 probe_data missing bcache data 2024-02-20 18:21:01,947 WARNING curtin:426 probe_data missing dasd data 2024-02-20 18:21:01,947 WARNING curtin:426 probe_data missing dmcrypt data 2024-02-20 18:21:02,317 WARNING curtin:426 probe_data missing lvm data 2024-02-20 18:21:02,317 WARNING curtin:426 probe_data missing raid data 2024-02-20 18:21:02,317 WARNING curtin:426 probe_data missing mount data 2024-02-20 18:21:02,317 WARNING curtin:426 probe_data missing zfs data 2024-02-20 18:21:02,318 INFO curtin:1351 Validating extracted storage config components 2024-02-20 18:22:37,649 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-02-20 18:22:37,650 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-02-20 18:23:43,169 ERROR root:30 finish: subiquity/Filesystem/_probe/probe_once: FAIL: cancelled 2024-02-20 18:23:43,169 ERROR block-discover:1288 block probing failed restricted=False Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/context.py", line 149, in decorated_async return await meth(self, **kw) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1250, in _probe_once storage = await self.app.prober.get_storage(probe_types) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/prober.py", line 66, in get_storage return await run_in_thread(run_probert, probe_types) File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquitycore/async_helpers.py", line 59, in run_in_thread return await loop.run_in_executor(None, func, *args) asyncio.exceptions.CancelledError During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3.10/asyncio/tasks.py", line 456, in wait_for return fut.result() asyncio.exceptions.CancelledError The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/6/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1282, in _probe await asyncio.wait_for(self._probe_once_task.task, 90.0) File "/snap/ubuntu-desktop-bootstrap/6/usr/lib/python3.10/asyncio/tasks.py", line 458, in wait_for raise exceptions.TimeoutError() from exc asyncio.exceptions.TimeoutError 2024-02-20 18:23:43,175 INFO subiquity.common.errorreport:424 saving crash report 'block probing crashed with TimeoutError' to /var/crash/1708453423.171495438.block_probe_fail.crash 2024-02-20 18:23:43,176 INFO root:30 start: subiquity/ErrorReporter/1708453423.171495438.block_probe_fail/add_info: 2024-02-20 18:23:44,701 INFO root:30 finish: subiquity/ErrorReporter/1708453423.171495438.block_probe_fail/add_info: SUCCESS: written to /var/crash/1708453423.171495438.block_probe_fail.crash 2024-02-20 18:25:09,232 WARNING curtin:426 probe_data missing bcache data 2024-02-20 18:25:09,830 WARNING curtin:426 probe_data missing dasd data 2024-02-20 18:25:09,830 WARNING curtin:426 probe_data missing dmcrypt data 2024-02-20 18:25:10,290 WARNING curtin:426 probe_data missing lvm data 2024-02-20 18:25:10,290 WARNING curtin:426 probe_data missing raid data 2024-02-20 18:25:10,290 WARNING curtin:426 probe_data missing mount data 2024-02-20 18:25:10,290 WARNING curtin:426 probe_data missing zfs data 2024-02-20 18:25:10,290 INFO curtin:1351 Validating extracted storage config components 2024-02-20 18:36:00,067 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2024-02-20 18:36:00,068 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-02-20 18:36:02,371 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/guided crashed with ValueError' to /var/crash/1708454162.370382309.server_request_fail.crash 2024-02-20 18:36:02,371 INFO root:30 start: subiquity/ErrorReporter/1708454162.370382309.server_request_fail/add_info: