2023-09-01 13:18:19,783 INFO subiquity:199 Starting Subiquity server revision 1219 of snap /snap/ubuntu-desktop-installer/1219
2023-09-01 13:18:19,783 INFO subiquity:200 Arguments passed: ['/snap/ubuntu-desktop-installer/1219/bin/subiquity/subiquity/cmd/server.py', '--use-os-prober', '--storage-version=2', '--postinst-hooks-dir=/snap/ubuntu-desktop-installer/1219/etc/subiquity/postinst.d']
2023-09-01 13:18:19,861 INFO aiohttp.access:206  [01/Sep/2023:13:18:19 +0000] "GET /meta/status HTTP/1.1" 200 420 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,664 INFO root:30 start: subiquity/apply_autoinstall_config: 
2023-09-01 13:18:20,666 INFO root:30 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2023-09-01 13:18:20,989 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-09-01 13:18:20,990 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-09-01 13:18:20,991 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:18:20,991 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:18:20,992 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,993 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:18:20,993 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:18:20,994 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,994 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:18:20,995 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:18:20,995 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,995 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-09-01 13:18:20,995 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-09-01 13:18:20,995 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,995 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:18:20,996 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-09-01 13:18:20,996 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-09-01 13:18:20,996 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /meta/interactive_sections HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,996 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:18:20,996 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:18:20,996 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,997 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +0000] "GET /locale HTTP/1.1" 200 195 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:20,997 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-09-01 13:18:20,998 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-09-01 13:18:20,998 INFO aiohttp.access:206  [01/Sep/2023:13:18:20 +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-01 13:18:31,480 INFO curtin:1347 Validating extracted storage config components
2023-09-01 13:18:45,862 INFO aiohttp.access:206  [01/Sep/2023:13:18:45 +0000] "POST /locale HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:18:53,428 ERROR subiquity.server.controllers.refresh:197 checking for snap update failed
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1219/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/1219/bin/subiquity/subiquity/common/api/client.py", line 47, in impl
    async with make_request(
  File "/snap/ubuntu-desktop-installer/1219/usr/lib/python3.10/contextlib.py", line 199, in __aenter__
    return await anext(self.gen)
  File "/snap/ubuntu-desktop-installer/1219/bin/subiquity/subiquity/server/snapdapi.py", line 298, in make_request
    content = await async_snapd.get(path[1:], **params)
  File "/snap/ubuntu-desktop-installer/1219/bin/subiquity/subiquitycore/snapd.py", line 198, in get
    response.raise_for_status()
  File "/snap/ubuntu-desktop-installer/1219/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-01 13:18:53,431 INFO aiohttp.access:206  [01/Sep/2023:13:18:21 +0000] "GET /refresh?wait=true HTTP/1.1" 200 280 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:19:01,376 ERROR root:30 finish: subiquity/Meta/status_GET: FAIL: cancelled
2023-09-01 13:19:02,349 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-09-01 13:19:02,350 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-09-01 13:19:08,473 INFO curtin:1347 Validating extracted storage config components
2023-09-01 13:19:30,158 ERROR root:30 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2023-09-01 13:29:35,483 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:29:35,483 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:29:35,484 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,486 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:29:35,486 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:29:35,487 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,487 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:29:35,487 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:29:35,487 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,487 INFO root:30 start: subiquity/Meta/client_variant_POST: 
2023-09-01 13:29:35,487 INFO root:30 finish: subiquity/Meta/client_variant_POST: SUCCESS: 200 null
2023-09-01 13:29:35,488 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "POST /meta/client_variant?variant=%22desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,488 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:29:35,489 INFO root:30 start: subiquity/Meta/interactive_sections_GET: 
2023-09-01 13:29:35,490 INFO root:30 finish: subiquity/Meta/interactive_sections_GET: SUCCESS: 200 null
2023-09-01 13:29:35,490 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/interactive_sections HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,491 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:29:35,491 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2023-09-01 13:29:35,491 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/status HTTP/1.1" 200 412 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,492 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /locale HTTP/1.1" 200 200 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,492 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-09-01 13:29:35,493 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-09-01 13:29:35,493 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +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-01 13:29:35,536 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /refresh?wait=true HTTP/1.1" 200 284 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:35,993 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'maps', 'raw', 'format', '%w,%d,%N']
2023-09-01 13:29:35,993 ERROR probert.multipath:38 Failed to run cmd: ['multipathd', 'show', 'paths', 'raw', 'format', '%d,%z,%m,%N,%n,%R,%r,%a']
2023-09-01 13:29:40,593 INFO curtin:1347 Validating extracted storage config components
2023-09-01 13:29:50,354 INFO aiohttp.access:206  [01/Sep/2023:13:29:50 +0000] "POST /locale HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:50,362 INFO aiohttp.access:206  [01/Sep/2023:13:29:50 +0000] "GET /storage/has_rst HTTP/1.1" 200 191 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:50,379 INFO aiohttp.access:206  [01/Sep/2023:13:29:50 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:50,385 INFO aiohttp.access:206  [01/Sep/2023:13:29:50 +0000] "GET /keyboard HTTP/1.1" 200 41538 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:50,427 INFO aiohttp.access:206  [01/Sep/2023:13:29:50 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:29:57,626 INFO aiohttp.access:206  [01/Sep/2023:13:29:57 +0000] "POST /keyboard/input_source?user=%22ubuntu%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:06,998 INFO aiohttp.access:206  [01/Sep/2023:13:30:06 +0000] "POST /keyboard HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:31,446 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-09-01 13:30:31,447 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-09-01 13:30:31,447 INFO aiohttp.access:206  [01/Sep/2023:13:30:31 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22network%22%5D HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:31,450 INFO aiohttp.access:206  [01/Sep/2023:13:30:31 +0000] "GET /refresh?wait=true HTTP/1.1" 200 284 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:31,454 INFO aiohttp.access:206  [01/Sep/2023:13:30:31 +0000] "GET /source HTTP/1.1" 200 403 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:31,456 INFO aiohttp.access:206  [01/Sep/2023:13:30:31 +0000] "GET /drivers HTTP/1.1" 200 268 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:31,458 INFO aiohttp.access:206  [01/Sep/2023:13:30:31 +0000] "GET /codecs HTTP/1.1" 200 205 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,332 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "POST /source?source_id=%22ubuntu-desktop%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,334 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "POST /drivers HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,334 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "POST /codecs HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,353 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 15177 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,363 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 15177 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,371 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "GET /storage/v2/guided?wait=true HTTP/1.1" 200 6292 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:44,372 INFO aiohttp.access:206  [01/Sep/2023:13:30:44 +0000] "GET /storage/has_bitlocker HTTP/1.1" 200 188 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:58,045 INFO curtin:1347 Validating extracted storage config components
2023-09-01 13:30:58,095 INFO aiohttp.access:206  [01/Sep/2023:13:30:57 +0000] "GET /storage/v2/orig_config HTTP/1.1" 200 15103 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:30:58,103 INFO aiohttp.access:206  [01/Sep/2023:13:30:58 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 15177 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:31:53,167 INFO aiohttp.access:206  [01/Sep/2023:13:31:53 +0000] "POST /storage/v2/edit_partition HTTP/1.1" 200 15217 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:13,521 INFO aiohttp.access:206  [01/Sep/2023:13:32:13 +0000] "POST /storage/v2 HTTP/1.1" 200 15217 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:13,522 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2023-09-01 13:32:13,523 INFO aiohttp.access:206  [01/Sep/2023:13:29:35 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 423 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:13,542 INFO aiohttp.access:206  [01/Sep/2023:13:32:13 +0000] "GET /storage/v2?wait=true HTTP/1.1" 200 15217 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:13,542 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:32:13,916 INFO curtin:1347 Validating extracted storage config components
2023-09-01 13:32:13,969 INFO aiohttp.access:206  [01/Sep/2023:13:32:13 +0000] "GET /storage/v2/orig_config HTTP/1.1" 200 15103 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:23,650 INFO aiohttp.access:206  [01/Sep/2023:13:32:23 +0000] "GET /timezone HTTP/1.1" 200 234 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:23,850 INFO root:30 start: subiquity/Meta/confirm_POST: 
2023-09-01 13:32:23,850 INFO root:30 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2023-09-01 13:32:23,850 INFO aiohttp.access:206  [01/Sep/2023:13:32:23 +0000] "POST /meta/confirm?tty=%22%2Fdev%2Ftty1%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:23,851 INFO root:30 start: subiquity/Install/install/configure_apt: configuring apt
2023-09-01 13:32:23,851 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-09-01 13:32:23,851 INFO aiohttp.access:206  [01/Sep/2023:13:32:13 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:23,876 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:32:30,446 INFO aiohttp.access:206  [01/Sep/2023:13:32:30 +0000] "POST /timezone?tz=%22Europe%2FRome%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:30,447 INFO aiohttp.access:206  [01/Sep/2023:13:32:30 +0000] "GET /identity HTTP/1.1" 200 259 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:30,448 INFO aiohttp.access:206  [01/Sep/2023:13:32:30 +0000] "GET /active_directory/has_support HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:37,561 INFO aiohttp.access:206  [01/Sep/2023:13:32:37 +0000] "GET /identity/validate_username?username=%22c%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:38,085 INFO aiohttp.access:206  [01/Sep/2023:13:32:38 +0000] "GET /identity/validate_username?username=%22co%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:38,601 INFO aiohttp.access:206  [01/Sep/2023:13:32:38 +0000] "GET /identity/validate_username?username=%22cor%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:38,736 INFO aiohttp.access:206  [01/Sep/2023:13:32:38 +0000] "GET /identity/validate_username?username=%22corr%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:39,080 INFO aiohttp.access:206  [01/Sep/2023:13:32:39 +0000] "GET /identity/validate_username?username=%22corra%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:39,312 INFO aiohttp.access:206  [01/Sep/2023:13:32:39 +0000] "GET /identity/validate_username?username=%22corrad%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:32:39,909 INFO aiohttp.access:206  [01/Sep/2023:13:32:39 +0000] "GET /identity/validate_username?username=%22corrado%22 HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:33:07,251 INFO root:30 finish: subiquity/Install/install/configure_apt: SUCCESS: configuring apt
2023-09-01 13:33:07,251 INFO root:30 start: subiquity/Install/install/curtin_install: installing system
2023-09-01 13:33:45,372 INFO root:30 start: subiquity/Meta/mark_configured_POST: 
2023-09-01 13:33:45,373 INFO root:30 finish: subiquity/Meta/mark_configured_POST: SUCCESS: 200 null
2023-09-01 13:33:45,373 INFO aiohttp.access:206  [01/Sep/2023:13:33:45 +0000] "POST /meta/mark_configured?endpoint_names=%5B%22active_directory%22%5D HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:33:45,395 INFO aiohttp.access:206  [01/Sep/2023:13:33:45 +0000] "POST /identity HTTP/1.1" 200 190 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:33:52,631 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:33:52,631 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-09-01 13:33:52,631 INFO aiohttp.access:206  [01/Sep/2023:13:33:52 +0000] "GET /meta/status HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:33:52,670 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:33:52,670 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-09-01 13:33:52,670 INFO aiohttp.access:206  [01/Sep/2023:13:33:52 +0000] "GET /meta/status HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:33:52,894 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:35:45,975 INFO root:30 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system
2023-09-01 13:35:45,975 INFO root:30 start: subiquity/Install/install/postinstall: final system configuration
2023-09-01 13:35:45,983 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-09-01 13:35:45,983 INFO aiohttp.access:206  [01/Sep/2023:13:32:23 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:35:45,983 INFO root:30 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2023-09-01 13:35:45,983 INFO aiohttp.access:206  [01/Sep/2023:13:33:52 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 421 "-" "Dart/3.0 (dart:io)"
2023-09-01 13:35:45,983 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:35:45,984 INFO root:30 start: subiquity/Meta/status_GET: 
2023-09-01 13:35:47,977 ERROR root:30 finish: subiquity/Install/install/postinstall/install_ubuntu-restricted-addons/retrieving: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.12"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:47,977 ERROR subiquity.server.controllers.install:731 failed to download package ubuntu-restricted-addons
2023-09-01 13:35:49,548 ERROR root:30 finish: subiquity/Install/install/postinstall/install_ubuntu-restricted-addons/retrieving: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.13"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:49,548 ERROR subiquity.server.controllers.install:731 failed to download package ubuntu-restricted-addons
2023-09-01 13:35:54,397 ERROR root:30 finish: subiquity/Install/install/postinstall/install_ubuntu-restricted-addons/retrieving: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:54,397 ERROR subiquity.server.controllers.install:731 failed to download package ubuntu-restricted-addons
2023-09-01 13:35:54,397 ERROR root:30 finish: subiquity/Install/install/postinstall/install_ubuntu-restricted-addons: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:54,398 ERROR root:30 finish: subiquity/Install/install/postinstall: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:54,399 INFO subiquity.common.errorreport:415 saving crash report 'install failed crashed with CalledProcessError' to /var/crash/1693575354.398155212.install_fail.crash
2023-09-01 13:35:54,399 ERROR root:30 finish: subiquity/Install/install: FAIL: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:54,399 INFO root:30 start: subiquity/ErrorReporter/1693575354.398155212.install_fail/add_info: 
2023-09-01 13:35:54,399 ERROR subiquity.server.server:413 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1219/bin/subiquity/subiquity/server/controllers/shutdown.py", line 74, in _wait_install
    await self.app.controllers.Install.install_task
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.
2023-09-01 13:35:54,400 ERROR subiquity.server.server:413 top level error
Traceback (most recent call last):
  File "/snap/ubuntu-desktop-installer/1219/bin/subiquity/subiquity/server/controllers/shutdown.py", line 74, in _wait_install
    await self.app.controllers.Install.install_task
subprocess.CalledProcessError: Command '['systemd-run', '--wait', '--same-dir', '--property', 'SyslogIdentifier=subiquity_log.4812', '--setenv', 'PATH=/snap/ubuntu-desktop-installer/1219/bin/subiquity/bin:/snap/ubuntu-desktop-installer/1219/bin/subiquity/usr/bin:/snap/ubuntu-desktop-installer/1219/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/usr/sbin:/snap/ubuntu-desktop-installer/1219/usr/bin:/snap/ubuntu-desktop-installer/1219/sbin:/snap/ubuntu-desktop-installer/1219/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/1219/lib/python3.10/site-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/dist-packages:/snap/ubuntu-desktop-installer/1219/usr/lib/python3/site-packages:', '--setenv', 'PYTHON=/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '--setenv', 'SNAP=/snap/ubuntu-desktop-installer/1219', '--', '/snap/ubuntu-desktop-installer/1219/usr/bin/python3.10', '-m', 'curtin', '--showtrace', '-vvv', '--set', 'json:reporting={"subiquity": {"type": "journald", "identifier": "curtin_event.4812.14"}}', 'system-install', '-t', '/target', '--download-only', '--', 'ubuntu-restricted-addons']' returned non-zero exit status 100.