2021-05-04 15:14:53,606 INFO subiquity:109 Starting Subiquity server revision 2280
2021-05-04 15:14:53,606 INFO subiquity:110 Arguments passed: ['/snap/subiquity/2280/usr/bin/subiquity-server']
2021-05-04 15:14:55,466 INFO root:39 start: subiquity/apply_autoinstall_config: 
2021-05-04 15:14:55,503 INFO root:39 finish: subiquity/apply_autoinstall_config: SUCCESS: 
2021-05-04 15:14:55,547 ERROR root:39 finish: subiquity/Refresh/check_for_update: FAIL: cancelled
2021-05-04 15:14:55,720 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,723 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2021-05-04 15:14:55,736 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,773 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,773 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2021-05-04 15:14:55,774 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status?cur=null HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,775 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /locale HTTP/1.1" 200 188 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,783 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,896 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,897 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2021-05-04 15:14:55,897 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,927 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,929 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr...
2021-05-04 15:14:55,935 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status?cur=null HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,937 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /locale HTTP/1.1" 200 188 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:55,937 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:14:55,947 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /network/global_addresses HTTP/1.1" 200 203 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:14:56,280 INFO curtin:1338 Validating extracted storage config components
2021-05-04 15:15:04,222 INFO aiohttp.access:206  [04/May/2021:15:15:04 +0000] "POST /locale HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:04,224 INFO aiohttp.access:206  [04/May/2021:15:15:04 +0000] "GET /refresh HTTP/1.1" 200 276 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:04,225 INFO aiohttp.access:206  [04/May/2021:15:15:04 +0000] "GET /keyboard HTTP/1.1" 200 233 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:28,832 INFO aiohttp.access:206  [04/May/2021:15:15:28 +0000] "POST /keyboard HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:28,835 INFO aiohttp.access:206  [04/May/2021:15:15:28 +0000] "GET /zdev HTTP/1.1" 200 180 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:28,840 INFO aiohttp.access:206  [04/May/2021:15:15:28 +0000] "GET /network HTTP/1.1" 200 2802 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:28,840 INFO root:39 start: subiquity/Network/apply_config: silent=True
2021-05-04 15:15:28,885 INFO aiohttp.access:206  [04/May/2021:15:15:28 +0000] "PUT /network/subscription?socket_path=%22/tmp/tmpxyrpzwun/socket%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:38,994 INFO aiohttp.access:206  [04/May/2021:15:15:38 +0000] "POST /network HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:38,997 INFO aiohttp.access:206  [04/May/2021:15:15:38 +0000] "GET /proxy HTTP/1.1" 200 187 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:39,017 INFO aiohttp.access:206  [04/May/2021:15:15:39 +0000] "DELETE /network/subscription?socket_path=%22/tmp/tmpxyrpzwun/socket%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:39,930 INFO root:39 finish: subiquity/Network/apply_config: SUCCESS: silent=True
2021-05-04 15:15:40,376 INFO aiohttp.access:206  [04/May/2021:15:15:40 +0000] "POST /proxy HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:40,380 INFO aiohttp.access:206  [04/May/2021:15:15:40 +0000] "GET /mirror HTTP/1.1" 200 223 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:41,401 INFO aiohttp.access:206  [04/May/2021:15:15:41 +0000] "POST /mirror HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:41,403 INFO aiohttp.access:206  [04/May/2021:15:15:41 +0000] "GET /refresh HTTP/1.1" 200 276 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:41,411 INFO aiohttp.access:206  [04/May/2021:15:15:41 +0000] "GET /storage HTTP/1.1" 200 319 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:41,867 INFO curtin:1338 Validating extracted storage config components
2021-05-04 15:15:41,916 INFO aiohttp.access:206  [04/May/2021:15:15:41 +0000] "GET /storage?wait=true HTTP/1.1" 200 4069 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:49,632 INFO aiohttp.access:206  [04/May/2021:15:15:49 +0000] "POST /storage HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:49,633 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2021-05-04 15:15:49,633 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:49,633 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i...
2021-05-04 15:15:49,634 INFO aiohttp.access:206  [04/May/2021:15:14:55 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:49,635 INFO aiohttp.access:206  [04/May/2021:15:15:49 +0000] "GET /identity HTTP/1.1" 200 183 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:49,636 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:15:49,638 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:15:51,605 INFO root:39 start: subiquity/Meta/confirm_POST: 
2021-05-04 15:15:51,605 INFO root:39 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null
2021-05-04 15:15:51,606 INFO aiohttp.access:206  [04/May/2021:15:15:51 +0000] "POST /meta/confirm?tty=%22/dev/tty1%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:51,606 INFO root:39 start: subiquity/Install/install/curtin_install: installing system
2021-05-04 15:15:51,661 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2021-05-04 15:15:51,662 INFO aiohttp.access:206  [04/May/2021:15:15:49 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 420 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:51,663 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini...
2021-05-04 15:15:51,663 INFO aiohttp.access:206  [04/May/2021:15:15:49 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 420 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:51,666 INFO aiohttp.access:206  [04/May/2021:15:15:51 +0000] "GET /identity HTTP/1.1" 200 258 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:15:51,666 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:15:51,672 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:16:25,598 INFO aiohttp.access:206  [04/May/2021:15:16:25 +0000] "POST /identity HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:16:25,601 INFO aiohttp.access:206  [04/May/2021:15:16:25 +0000] "GET /ssh HTTP/1.1" 200 252 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:16:30,511 INFO aiohttp.access:206  [04/May/2021:15:16:30 +0000] "POST /ssh HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:16:30,650 INFO aiohttp.access:206  [04/May/2021:15:16:30 +0000] "GET /snaplist HTTP/1.1" 200 46939 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:16:34,976 INFO aiohttp.access:206  [04/May/2021:15:16:34 +0000] "POST /snaplist HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:11,448 INFO root:39 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system
2021-05-04 15:21:11,453 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_WAIT", "confirming_tty": "/dev/tty1", "error": null, "cloud_i...
2021-05-04 15:21:11,523 INFO aiohttp.access:206  [04/May/2021:15:15:51 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:11,523 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_WAIT", "confirming_tty": "/dev/tty1", "error": null, "cloud_i...
2021-05-04 15:21:11,524 INFO aiohttp.access:206  [04/May/2021:15:15:51 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:11,531 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:21:11,532 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:21:16,641 INFO root:39 start: subiquity/Install/install/postinstall: final system configuration
2021-05-04 15:21:16,658 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "clou...
2021-05-04 15:21:16,658 INFO aiohttp.access:206  [04/May/2021:15:21:11 +0000] "GET /meta/status?cur=%22POST_WAIT%22 HTTP/1.1" 200 425 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:16,659 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "clou...
2021-05-04 15:21:16,659 INFO aiohttp.access:206  [04/May/2021:15:21:11 +0000] "GET /meta/status?cur=%22POST_WAIT%22 HTTP/1.1" 200 425 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:16,663 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:21:16,673 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:21:39,904 INFO root:39 finish: subiquity/Install/install/postinstall: SUCCESS: final system configuration
2021-05-04 15:21:39,913 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_...
2021-05-04 15:21:39,914 INFO aiohttp.access:206  [04/May/2021:15:21:16 +0000] "GET /meta/status?cur=%22POST_RUNNING%22 HTTP/1.1" 200 423 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:39,914 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_...
2021-05-04 15:21:39,915 INFO aiohttp.access:206  [04/May/2021:15:21:16 +0000] "GET /meta/status?cur=%22POST_RUNNING%22 HTTP/1.1" 200 423 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:21:39,918 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:21:39,926 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:25:03,094 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "DONE", "confirming_tty": "/dev/tty1", "error": null, "cloud_init_o...
2021-05-04 15:25:03,215 INFO aiohttp.access:206  [04/May/2021:15:21:39 +0000] "GET /meta/status?cur=%22UU_RUNNING%22 HTTP/1.1" 200 417 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:25:03,216 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "DONE", "confirming_tty": "/dev/tty1", "error": null, "cloud_init_o...
2021-05-04 15:25:03,216 INFO aiohttp.access:206  [04/May/2021:15:21:39 +0000] "GET /meta/status?cur=%22UU_RUNNING%22 HTTP/1.1" 200 417 "-" "Python/3.6 aiohttp/3.7.3"
2021-05-04 15:25:03,264 INFO root:39 start: subiquity/Meta/status_GET: 
2021-05-04 15:25:03,265 INFO root:39 start: subiquity/Meta/status_GET: