2021-06-26 21:45:20,905 INFO subiquity:118 Starting Subiquity revision 2280
2021-06-26 21:45:20,905 INFO subiquity:119 Arguments passed: ['/snap/subiquity/2280/usr/bin/subiquity']
2021-06-26 21:45:20,906 DEBUG subiquitycore.screen:135 KDGKBTYPE returned b'\x02', is_linux_tty True
2021-06-26 21:45:20,907 DEBUG asyncio:54 Using selector: EpollSelector
2021-06-26 21:45:22,936 DEBUG subiquity.signals:50 connect_signal: l10n:language-selected -> KeyboardController.language_selected
2021-06-26 21:45:22,936 DEBUG subiquitycore.core:92 known signals: ['l10n:language-selected']
2021-06-26 21:45:22,937 DEBUG subiquitycore.core:120 starting controllers
2021-06-26 21:45:22,937 DEBUG subiquitycore.core:123 controllers started
2021-06-26 21:45:22,940 DEBUG subiquity/Progress/_wait_status:107 start: 
2021-06-26 21:45:22,942 INFO subiquity/Welcome:107 start: starting UI
2021-06-26 21:45:22,953 DEBUG subiquity.views.welcome:106 ('ast', 'en_US')
2021-06-26 21:45:22,955 DEBUG subiquity.views.welcome:106 ('id', 'en_US')
2021-06-26 21:45:22,955 DEBUG subiquity.views.welcome:106 ('ca', 'en_US')
2021-06-26 21:45:22,955 DEBUG subiquity.views.welcome:106 ('de', 'en_US')
2021-06-26 21:45:22,955 DEBUG subiquity.views.welcome:106 ('en_US', 'en_US')
2021-06-26 21:45:22,956 DEBUG subiquity.views.welcome:106 ('en_GB', 'en_US')
2021-06-26 21:45:22,956 DEBUG subiquity.views.welcome:106 ('es', 'en_US')
2021-06-26 21:45:22,956 DEBUG subiquity.views.welcome:106 ('fr', 'en_US')
2021-06-26 21:45:22,956 DEBUG subiquity.views.welcome:106 ('hr', 'en_US')
2021-06-26 21:45:22,957 DEBUG subiquity.views.welcome:106 ('lv', 'en_US')
2021-06-26 21:45:22,957 DEBUG subiquity.views.welcome:106 ('lt', 'en_US')
2021-06-26 21:45:22,959 DEBUG subiquity.views.welcome:106 ('hu', 'en_US')
2021-06-26 21:45:22,959 DEBUG subiquity.views.welcome:106 ('nl', 'en_US')
2021-06-26 21:45:22,959 DEBUG subiquity.views.welcome:106 ('nb', 'en_US')
2021-06-26 21:45:22,959 DEBUG subiquity.views.welcome:106 ('pl', 'en_US')
2021-06-26 21:45:22,960 DEBUG subiquity.views.welcome:106 ('fi', 'en_US')
2021-06-26 21:45:22,960 DEBUG subiquity.views.welcome:106 ('sv', 'en_US')
2021-06-26 21:45:22,960 DEBUG subiquity.views.welcome:106 ('cs', 'en_US')
2021-06-26 21:45:22,961 DEBUG subiquity.views.welcome:106 ('el', 'en_US')
2021-06-26 21:45:22,961 DEBUG subiquity.views.welcome:106 ('be', 'en_US')
2021-06-26 21:45:22,961 DEBUG subiquity.views.welcome:106 ('ru', 'en_US')
2021-06-26 21:45:22,961 DEBUG subiquity.views.welcome:106 ('sr', 'en_US')
2021-06-26 21:45:22,961 DEBUG subiquity.views.welcome:106 ('uk', 'en_US')
2021-06-26 21:52:00,076 DEBUG subiquity.views.welcome:163 WelcomeView en_US
2021-06-26 21:52:00,076 DEBUG subiquity.client.controllers.welcome:43 WelcomeController.done en_US next_screen
2021-06-26 21:52:00,077 DEBUG subiquity.client.controllers.keyboard:43 language_selected en_US
2021-06-26 21:52:00,203 DEBUG subiquity.client.controllers.keyboard:48 loading language C
2021-06-26 21:52:00,251 INFO subiquity/Welcome:112 finish: completed SUCCESS
2021-06-26 21:52:00,252 INFO subiquity/Refresh:107 start: starting UI
2021-06-26 21:52:00,253 INFO subiquity/Refresh:112 finish: (skipped) SUCCESS
2021-06-26 21:52:00,254 DEBUG subiquitycore.tui:200 skipping screen Refresh
2021-06-26 21:52:00,254 INFO subiquity/Keyboard:107 start: starting UI
2021-06-26 21:52:30,370 DEBUG subiquity.ui.views.keyboard:480 select_layout br
2021-06-26 21:52:43,809 DEBUG subiquity.client.controllers.keyboard:69 KeyboardController.done KeyboardSetting(layout='br', variant='', toggle=None) next_screen
2021-06-26 21:52:43,821 DEBUG subiquitycore.utils:74 arun_command called: ['setupcon', '--save', '--force', '--keyboard-only']
2021-06-26 21:52:45,601 DEBUG subiquitycore.utils:83 arun_command ['setupcon', '--save', '--force', '--keyboard-only'] exited with code 0
2021-06-26 21:52:45,601 DEBUG subiquitycore.utils:74 arun_command called: ['/snap/bin/subiquity.subiquity-loadkeys']
2021-06-26 21:52:45,634 DEBUG subiquitycore.utils:83 arun_command ['/snap/bin/subiquity.subiquity-loadkeys'] exited with code 0
2021-06-26 21:52:45,634 DEBUG subiquity.client.controllers.keyboard:69 KeyboardController.done KeyboardSetting(layout='br', variant='', toggle=None) next_screen
2021-06-26 21:52:45,638 INFO subiquity/Keyboard:112 finish: completed SUCCESS
2021-06-26 21:52:45,639 INFO subiquity/Zdev:107 start: starting UI
2021-06-26 21:52:45,640 INFO subiquity/Zdev:112 finish: (skipped) SUCCESS
2021-06-26 21:52:45,640 DEBUG subiquitycore.tui:200 skipping screen Zdev
2021-06-26 21:52:45,640 INFO subiquity/Network:107 start: starting UI
2021-06-26 21:52:45,644 DEBUG subiquitycore.views.network:339 new_link eth0 False
2021-06-26 21:52:45,658 DEBUG subiquity/Network/route_watch_POST:107 start: 
2021-06-26 21:52:45,658 DEBUG subiquitycore.views.network:306 view route_watcher [2]
2021-06-26 21:52:45,659 DEBUG subiquity/Network/route_watch_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:45,659 INFO aiohttp.access:206  [26/Jun/2021:21:52:45 +0000] "POST /route_watch?routes=%5B2%5D HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:52:46,217 DEBUG subiquity/Network/route_watch_POST:107 start: 
2021-06-26 21:52:46,218 DEBUG subiquitycore.views.network:306 view route_watcher []
2021-06-26 21:52:46,218 DEBUG subiquity/Network/route_watch_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:46,218 INFO aiohttp.access:206  [26/Jun/2021:21:52:46 +0000] "POST /route_watch?routes=%5B%5D HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:52:46,230 DEBUG subiquity/Network/update_link_POST:107 start: {"name": "eth0", "type": "eth", "is_connected": true, "bond_master": null, "i...
2021-06-26 21:52:46,230 DEBUG subiquitycore.views.network:357 update_link eth0 True
2021-06-26 21:52:46,231 DEBUG subiquity/Network/update_link_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:46,231 INFO aiohttp.access:206  [26/Jun/2021:21:52:46 +0000] "POST /update_link?act=%22CHANGE%22 HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:52:46,344 DEBUG subiquity/Network/update_link_POST:107 start: {"name": "eth0", "type": "eth", "is_connected": true, "bond_master": null, "i...
2021-06-26 21:52:46,344 DEBUG subiquitycore.views.network:357 update_link eth0 True
2021-06-26 21:52:46,345 DEBUG subiquity/Network/update_link_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:46,345 INFO aiohttp.access:206  [26/Jun/2021:21:52:46 +0000] "POST /update_link?act=%22CHANGE%22 HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:52:47,041 DEBUG subiquity/Network/update_link_POST:107 start: {"name": "eth0", "type": "eth", "is_connected": true, "bond_master": null, "i...
2021-06-26 21:52:47,042 DEBUG subiquitycore.views.network:357 update_link eth0 True
2021-06-26 21:52:47,042 DEBUG subiquity/Network/update_link_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:47,043 INFO aiohttp.access:206  [26/Jun/2021:21:52:47 +0000] "POST /update_link?act=%22CHANGE%22 HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:52:47,050 DEBUG subiquity/Network/route_watch_POST:107 start: 
2021-06-26 21:52:47,050 DEBUG subiquitycore.views.network:306 view route_watcher [2]
2021-06-26 21:52:47,050 DEBUG subiquity/Network/route_watch_POST:112 finish: 200 null SUCCESS
2021-06-26 21:52:47,052 INFO aiohttp.access:206  [26/Jun/2021:21:52:47 +0000] "POST /route_watch?routes=%5B2%5D HTTP/1.1" 200 174 "-" "Python/3.6 aiohttp/3.7.3"
2021-06-26 21:53:09,394 INFO subiquity/Network:112 finish: completed SUCCESS
2021-06-26 21:53:09,394 INFO subiquity/Proxy:107 start: starting UI
2021-06-26 21:53:15,268 DEBUG subiquity.ui.views.proxy:63 User input: {'url': ''}
2021-06-26 21:53:15,268 DEBUG subiquity.client.controllers.proxy:40 ProxyController.done next_screen proxy=
2021-06-26 21:53:15,272 INFO subiquity/Proxy:112 finish: completed SUCCESS
2021-06-26 21:53:15,272 INFO subiquity/Mirror:107 start: starting UI
2021-06-26 21:53:18,370 DEBUG subiquity.ui.mirror:60 User input: {'url': 'http://br.archive.ubuntu.com/ubuntu'}
2021-06-26 21:53:18,371 DEBUG subiquity.client.controllers.mirror:43 MirrorController.done next_screen mirror=http://br.archive.ubuntu.com/ubuntu
2021-06-26 21:53:18,374 INFO subiquity/Mirror:112 finish: completed SUCCESS
2021-06-26 21:53:18,375 INFO subiquity/Refresh:107 start: starting UI
2021-06-26 21:53:18,376 INFO subiquity/Refresh:112 finish: (skipped) SUCCESS
2021-06-26 21:53:18,376 DEBUG subiquitycore.tui:200 skipping screen Refresh-2
2021-06-26 21:53:18,377 INFO subiquity/Filesystem:107 start: starting UI
2021-06-26 21:53:18,379 DEBUG subiquity.models.filesystem:1377 load_server_data StorageResponse(status=<ProbeStatus.DONE: 3>, bootloader=<Bootloader.BIOS: 'BIOS'>, error_report=None, orig_config=[{'type': 'disk', 'id': 'disk-xvda', 'path': '/dev/xvda'}], config=[{'path': '/dev/xvda', 'preserve': True, 'name': '', 'grub_device': False, 'type': 'disk', 'id': 'disk-xvda'}], blockdev={'/dev/sr0': {'DEVLINKS': '/dev/disk/by-uuid/2021-02-01-17-57-41-00 /dev/dvd /dev/disk/by-id/ata-QEMU_DVD-ROM_QM00004 /dev/disk/by-label/Ubuntu-Server\\x2020.04.2\\x20LTS\\x20amd64 /dev/cdrom /dev/disk/by-path/pci-0000:00:01.1-ata-2', 'DEVNAME': '/dev/sr0', 'DEVPATH': '/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:1/1:0:1:0/block/sr0', 'DEVTYPE': 'disk', 'ID_ATA': '1', 'ID_BUS': 'ata', 'ID_CDROM': '1', 'ID_CDROM_DVD': '1', 'ID_CDROM_MEDIA': '1', 'ID_CDROM_MEDIA_DVD': '1', 'ID_CDROM_MEDIA_TRACK_COUNT_DATA': '1', 'ID_CDROM_MRW': '1', 'ID_CDROM_MRW_W': '1', 'ID_FOR_SEAT': 'block-pci-0000_00_01_1-ata-2', 'ID_FS_BOOT_SYSTEM_ID': 'EL\\x20TORITO\\x20SPECIFICATION', 'ID_FS_LABEL': 'Ubuntu-Server_20.04.2_LTS_amd64', 'ID_FS_LABEL_ENC': 'Ubuntu-Server\\x2020.04.2\\x20LTS\\x20amd64', 'ID_FS_TYPE': 'iso9660', 'ID_FS_USAGE': 'filesystem', 'ID_FS_UUID': '2021-02-01-17-57-41-00', 'ID_FS_UUID_ENC': '2021-02-01-17-57-41-00', 'ID_FS_VERSION': 'Joliet Extension', 'ID_MODEL': 'QEMU_DVD-ROM', 'ID_MODEL_ENC': 'QEMU\\x20DVD-ROM\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20', 'ID_PART_TABLE_TYPE': 'dos', 'ID_PART_TABLE_UUID': '63fcfd05', 'ID_PATH': 'pci-0000:00:01.1-ata-2', 'ID_PATH_TAG': 'pci-0000_00_01_1-ata-2', 'ID_REVISION': '0.10.2', 'ID_SCSI': '1', 'ID_SCSI_INQUIRY': '1', 'ID_SERIAL': 'QEMU_DVD-ROM_QM00004', 'ID_SERIAL_SHORT': 'QM00004', 'ID_TYPE': 'cd', 'ID_VENDOR': 'QEMU', 'ID_VENDOR_ENC': 'QEMU\\x20\\x20\\x20\\x20', 'MAJOR': '11', 'MINOR': '0', 'SCSI_MODEL': 'QEMU_DVD-ROM', 'SCSI_MODEL_ENC': 'QEMU\\x20DVD-ROM\\x20\\x20\\x20\\x20', 'SCSI_REVISION': '0.10', 'SCSI_TPGS': '0', 'SCSI_TYPE': 'cd/dvd', 'SCSI_VENDOR': 'QEMU', 'SCSI_VENDOR_ENC': 'QEMU\\x20\\x20\\x20\\x20', 'SUBSYSTEM': 'block', 'SYSTEMD_MOUNT_DEVICE_BOUND': '1', 'TAGS': ':seat:systemd:uaccess:', 'USEC_INITIALIZED': '1599382', 'attrs': {'alignment_offset': '0', 'bdi': None, 'capability': '119', 'dev': '11:0', 'device': None, 'discard_alignment': '0', 'events': 'media_change eject_request', 'events_async': '', 'events_poll_msecs': '-1', 'ext_range': '1', 'hidden': '0', 'inflight': '       0        0', 'range': '1', 'removable': '1', 'ro': '0', 'size': '1215168512', 'stat': '   10106       45  2370560    18136        0        0        0        0        0    16448      600        0        0        0        0', 'subsystem': 'block', 'uevent': 'MAJOR=11\nMINOR=0\nDEVNAME=sr0\nDEVTYPE=disk'}, 'partitiontable': {'label': 'dos', 'id': '0x63fcfd05', 'device': '/dev/sr0', 'unit': 'sectors', 'partitions': [{'node': '/dev/sr0p1', 'start': 0, 'size': 2373376, 'type': '0', 'bootable': True}, {'node': '/dev/sr0p2', 'start': 1364, 'size': 8000, 'type': 'ef'}]}}, '/dev/xvda': {'DEVNAME': '/dev/xvda', 'DEVPATH': '/devices/vbd-768/block/xvda', 'DEVTYPE': 'disk', 'MAJOR': '202', 'MINOR': '0', 'SUBSYSTEM': 'block', 'TAGS': ':systemd:', 'USEC_INITIALIZED': '1477533', 'attrs': {'alignment_offset': '0', 'bdi': None, 'capability': '10', 'dev': '202:0', 'device': None, 'discard_alignment': '0', 'events': '', 'events_async': '', 'events_poll_msecs': '-1', 'ext_range': '16', 'hidden': '0', 'inflight': '       0        0', 'range': '16', 'removable': '0', 'ro': '0', 'size': '10737418240', 'stat': '     588        0    21120      163        0        0        0        0        0      164       12        0        0        0        0', 'subsystem': 'block', 'uevent': 'MAJOR=202\nMINOR=0\nDEVNAME=xvda\nDEVTYPE=disk'}}}, dasd={})
2021-06-26 21:53:18,381 DEBUG subiquity.models.filesystem:1567 exclusions set()
2021-06-26 21:54:09,096 DEBUG subiquity.common.filesystem:199 partition_disk_handler: Disk(path='/dev/xvda', wipe='superblock', type='disk', id='disk-xvda') None {'size': 10735321088, 'fstype': 'ext4', 'mount': '/'}
2021-06-26 21:54:09,097 DEBUG subiquity.common.filesystem:200 disk.freespace: 10735321088
2021-06-26 21:54:09,097 DEBUG subiquity.common.filesystem:217 model needs a bootloader partition? True
2021-06-26 21:54:09,097 DEBUG subiquity.common.filesystem:117 _create_boot_partition - adding bios_grub partition
2021-06-26 21:54:09,097 DEBUG subiquity.models.filesystem:1745 add_partition: rounded size from 1048576 to 1048576
2021-06-26 21:54:09,097 DEBUG subiquity.common.filesystem:227 Adjusting request down: 10735321088 - 1048576 = 10734272512
2021-06-26 21:54:09,097 DEBUG subiquity.models.filesystem:1745 add_partition: rounded size from 10734272512 to 10734272512
2021-06-26 21:54:09,097 DEBUG subiquity.models.filesystem:1815 adding ext4 to Partition(device=disk-xvda, size=10734272512, wipe='superblock', flag='', grub_device=None, id='partition-1')
2021-06-26 21:54:09,098 DEBUG subiquity.common.filesystem:232 Successfully added partition
2021-06-26 21:54:42,467 DEBUG subiquity.client.controllers.filesystem:231 FilesystemController.finish next_screen
2021-06-26 21:54:42,467 DEBUG subiquity.models.filesystem:1632 mountpoints {'/': 'mount-0'}
2021-06-26 21:54:42,473 INFO subiquity/Filesystem:112 finish: completed SUCCESS
2021-06-26 21:54:42,473 INFO subiquity/Identity:107 start: starting UI
2021-06-26 21:54:42,477 DEBUG subiquity.client.client:404 showing InstallConfirmation over <FilesystemView selectable box/flow widget>
2021-06-26 21:54:46,977 INFO subiquity/Identity:107 start: starting UI
2021-06-26 21:55:34,432 DEBUG subiquity.client.controllers.identity:49 IdentityController.done next_screen user_spec=IdentityData(realname='', username='cloud', hostname='vps-ubuntu20')
2021-06-26 21:55:34,437 INFO subiquity/Identity:112 finish: completed SUCCESS
2021-06-26 21:55:34,437 INFO subiquity/SSH:107 start: starting UI
2021-06-26 21:55:46,038 DEBUG subiquity.ui.ssh:272 User input: {'install_server': True, 'ssh_import_id': None}
2021-06-26 21:55:46,039 DEBUG subiquity.client.controllers.ssh:123 SSHController.done next_screen result=SSHData(install_server=True, allow_pw=True, authorized_keys=[])
2021-06-26 21:55:46,043 INFO subiquity/SSH:112 finish: completed SUCCESS
2021-06-26 21:55:46,043 INFO subiquity/SnapList:107 start: starting UI
2021-06-26 21:55:46,082 DEBUG subiquity.views.snaplist:418 pre-seeded snaps {'lxd', 'core18', 'snapd'}
2021-06-26 21:56:34,891 DEBUG subiquity.views.snaplist:465 snaps to install {}
2021-06-26 21:56:34,891 DEBUG subiquity.client.controllers.snaplist:57 SnapListController.done next_screen snaps_to_install=[]
2021-06-26 21:56:34,895 INFO subiquity/SnapList:112 finish: completed SUCCESS
2021-06-26 21:56:34,896 INFO subiquity/Progress:107 start: starting UI