2024-05-05 10:42:49,588 INFO subiquity:201 Starting Subiquity server revision 171 of snap /snap/ubuntu-desktop-bootstrap/171 of version 0+git.2d119e1b3 2024-05-05 10:42:49,588 INFO subiquity:205 Arguments passed: ['/snap/ubuntu-desktop-bootstrap/171/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-bootstrap/171/etc/subiquity/postinst.d'] 2024-05-05 10:43:20,431 INFO root:38 start: subiquity/apply_autoinstall_config: 2024-05-05 10:43:20,442 INFO root:38 finish: subiquity/apply_autoinstall_config: SUCCESS: 2024-05-05 10:43:32,941 INFO root:38 start: subiquity/Meta/status_GET: 2024-05-05 10:43:32,943 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro... 2024-05-05 10:43:33,002 INFO root:38 start: subiquity/Meta/client_variant_POST: 2024-05-05 10:43:33,003 INFO root:38 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null 2024-05-05 10:43:33,132 INFO root:38 start: subiquity/Meta/status_GET: 2024-05-05 10:43:33,136 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro... 2024-05-05 10:43:33,140 INFO root:38 start: subiquity/Meta/mark_configured_POST: 2024-05-05 10:43:33,152 INFO root:38 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null 2024-05-05 10:43:33,881 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-05-05 10:43:33,882 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-05-05 10:43:35,382 INFO root:38 start: subiquity/Meta/status_GET: 2024-05-05 10:43:35,382 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro... 2024-05-05 10:43:35,383 INFO root:38 start: subiquity/Meta/status_GET: 2024-05-05 10:43:35,384 INFO root:38 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "nonreportable_erro... 2024-05-05 10:43:35,392 INFO root:38 start: subiquity/Meta/status_GET: 2024-05-05 10:43:35,394 INFO root:38 start: subiquity/Meta/interactive_sections_GET: 2024-05-05 10:43:35,395 INFO root:38 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null 2024-05-05 10:43:38,653 INFO curtin:1396 Validating extracted storage config components 2024-05-05 10:44:05,733 ERROR subiquity.server.controllers.refresh:197 checking for snap update failed Traceback (most recent call last): File "/snap/ubuntu-desktop-bootstrap/171/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/171/bin/subiquity/subiquity/common/api/client.py", line 47, in impl async with make_request( File "/snap/ubuntu-desktop-bootstrap/171/usr/lib/python3.10/contextlib.py", line 199, in __aenter__ return await anext(self.gen) File "/snap/ubuntu-desktop-bootstrap/171/bin/subiquity/subiquity/server/snapdapi.py", line 298, in make_request content = await async_snapd.get(path[1:], **params) File "/snap/ubuntu-desktop-bootstrap/171/bin/subiquity/subiquitycore/snapd.py", line 226, in get response.raise_for_status() File "/snap/ubuntu-desktop-bootstrap/171/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-05-05 10:44:20,316 ERROR root:38 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2024-05-05 10:44:21,556 ERROR root:38 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2024-05-05 10:44:40,058 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N'] 2024-05-05 10:44:40,060 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a'] 2024-05-05 10:44:41,416 INFO curtin:1396 Validating extracted storage config components 2024-05-05 10:44:41,791 ERROR root:38 finish: subiquity/Drivers/_list_drivers/wait_apt: FAIL: cancelled 2024-05-05 10:44:41,791 ERROR root:38 finish: subiquity/Drivers/_list_drivers: FAIL: cancelled 2024-05-05 10:45:16,100 INFO curtin:1396 Validating extracted storage config components 2024-05-05 10:45:24,677 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/reformat_disk crashed with ValueError' to /var/crash/1714905924.676530361.server_request_fail.crash 2024-05-05 10:45:24,678 INFO root:38 start: subiquity/ErrorReporter/1714905924.676530361.server_request_fail/add_info: 2024-05-05 10:45:30,707 INFO subiquity.server.controllers.filesystem:1063 Resetting Filesystem model 2024-05-05 10:45:30,914 INFO curtin:1396 Validating extracted storage config components 2024-05-05 10:45:31,318 INFO root:38 finish: subiquity/ErrorReporter/1714905924.676530361.server_request_fail/add_info: SUCCESS: written to /var/crash/1714905924.676530361.server_request_fail.crash 2024-05-05 10:45:34,504 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/reformat_disk crashed with ValueError' to /var/crash/1714905934.502130032.server_request_fail.crash 2024-05-05 10:45:34,505 INFO root:38 start: subiquity/ErrorReporter/1714905934.502130032.server_request_fail/add_info: 2024-05-05 10:45:40,842 INFO root:38 finish: subiquity/ErrorReporter/1714905934.502130032.server_request_fail/add_info: SUCCESS: written to /var/crash/1714905934.502130032.server_request_fail.crash 2024-05-05 10:57:30,678 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/reformat_disk crashed with ValueError' to /var/crash/1714906650.677089930.server_request_fail.crash 2024-05-05 10:57:30,679 INFO root:38 start: subiquity/ErrorReporter/1714906650.677089930.server_request_fail/add_info: 2024-05-05 10:57:37,088 INFO root:38 finish: subiquity/ErrorReporter/1714906650.677089930.server_request_fail/add_info: SUCCESS: written to /var/crash/1714906650.677089930.server_request_fail.crash 2024-05-05 10:57:56,414 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/reformat_disk crashed with ValueError' to /var/crash/1714906676.413122416.server_request_fail.crash 2024-05-05 10:57:56,416 INFO root:38 start: subiquity/ErrorReporter/1714906676.413122416.server_request_fail/add_info: 2024-05-05 10:58:01,295 INFO subiquity.server.controllers.filesystem:1063 Resetting Filesystem model 2024-05-05 10:58:01,479 INFO curtin:1396 Validating extracted storage config components 2024-05-05 10:58:02,728 INFO root:38 finish: subiquity/ErrorReporter/1714906676.413122416.server_request_fail/add_info: SUCCESS: written to /var/crash/1714906676.413122416.server_request_fail.crash 2024-05-05 11:12:51,730 INFO subiquity.common.errorreport:424 saving crash report 'request to /storage/v2/reformat_disk crashed with ValueError' to /var/crash/1714907571.728676081.server_request_fail.crash 2024-05-05 11:12:51,731 INFO root:38 start: subiquity/ErrorReporter/1714907571.728676081.server_request_fail/add_info: