2023-09-08 16:00:52,014 INFO subiquity:199 Starting Subiquity server revision 1229 of snap /snap/ubuntu-desktop-installer/1229 2023-09-08 16:00:52,014 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1229/etc/subiquity/postinst.d'] 2023-09-08 16:00:52,799 INFO aiohttp.access:206 [08/Sep/2023:16:00:52 +0000] "GET /meta/status HTTP/1.1" 200 420 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:53,803 INFO aiohttp.access:206 [08/Sep/2023:16:00:53 +0000] "GET /meta/status HTTP/1.1" 200 420 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:55,202 INFO root:30 start: subiquity/apply_autoinstall_config: 2023-09-08 16:00:55,217 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 2023-09-08 16:00:58,248 INFO root:30 start: subiquity/Meta/status_GET: 2023-09-08 16:00:58,249 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-09-08 16:00:58,249 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,260 INFO root:30 start: subiquity/Meta/status_GET: 2023-09-08 16:00:58,260 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-09-08 16:00:58,261 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,262 INFO root:30 start: subiquity/Meta/status_GET: 2023-09-08 16:00:58,262 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-09-08 16:00:58,263 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,263 INFO root:30 start: subiquity/Meta/client_variant_POST: 2023-09-08 16:00:58,265 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2023-09-08 16:00:58,267 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,269 INFO root:30 start: subiquity/Meta/status_GET: 2023-09-08 16:00:58,271 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 2023-09-08 16:00:58,271 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2023-09-08 16:00:58,272 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /meta/interactive_sections HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,273 INFO root:30 start: subiquity/Meta/status_GET: 2023-09-08 16:00:58,274 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2023-09-08 16:00:58,274 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,285 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /locale HTTP/1.1" 200 195 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,287 INFO root:30 start: subiquity/Meta/mark_configured_POST: 2023-09-08 16:00:58,289 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2023-09-08 16:00:58,290 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22mirror%22%2C%22proxy%22%2C%22ssh%22%2C%22snaplist%22%2C%22ubuntu_pro%22%5D HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:00:58,311 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2023-09-08 16:00:58,313 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2023-09-08 16:01:35,037 ERROR subiquity.server.controllers.refresh:197 checking for snap update failed Traceback (most recent call last): File "/snap/ubuntu-desktop-installer/1229/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/1229/bin/subiquity/subiquity/common/api/client.py", line 47, in impl async with make_request( File "/snap/ubuntu-desktop-installer/1229/usr/lib/python3.10/contextlib.py", line 199, in __aenter__ return await anext(self.gen) File "/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquity/server/snapdapi.py", line 298, in make_request content = await async_snapd.get(path[1:], **params) File "/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquitycore/snapd.py", line 198, in get response.raise_for_status() File "/snap/ubuntu-desktop-installer/1229/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-09-08 16:01:35,040 INFO aiohttp.access:206 [08/Sep/2023:16:00:58 +0000] "GET /refresh?wait=true HTTP/1.1" 200 280 "-" "Dart/3.0 (dart:io)" 2023-09-08 16:02:28,251 ERROR root:30 finish: subiquity/Filesystem/_probe/probe_once: FAIL: cancelled 2023-09-08 16:02:28,252 ERROR block-discover:1227 block probing failed restricted=False Traceback (most recent call last): File "/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquitycore/context.py", line 149, in decorated_async return await meth(self, **kw) File "/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1189, in _probe_once storage = await self.app.prober.get_storage(probe_types) File "/snap/ubuntu-desktop-installer/1229/bin/subiquity/subiquitycore/prober.py", line 66, in get_storage return await run_in_thread(run_probert, probe_types) File "/snap/ubuntu-desktop-installer/1229/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-installer/1229/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-installer/1229/bin/subiquity/subiquity/server/controllers/filesystem.py", line 1221, in _probe await asyncio.wait_for(self._probe_once_task.task, 90.0) File "/snap/ubuntu-desktop-installer/1229/usr/lib/python3.10/asyncio/tasks.py", line 458, in wait_for raise exceptions.TimeoutError() from exc asyncio.exceptions.TimeoutError 2023-09-08 16:02:28,259 INFO subiquity.common.errorreport:415 saving crash report 'block probing crashed with TimeoutError' to /var/crash/1694188948.257439375.block_probe_fail.crash 2023-09-08 16:02:28,261 INFO root:30 start: subiquity/ErrorReporter/1694188948.257439375.block_probe_fail/add_info: