Файловый менеджер

Файловый менеджер - Редактировать - /var/log/installer/subiquity-server-debug.log.2111

Назад
2021-07-06 08:10:10,983 INFO subiquity:109 Starting Subiquity server revision 2280 2021-07-06 08:10:10,983 INFO subiquity:110 Arguments passed: ['/snap/subiquity/2280/usr/bin/subiquity-server'] 2021-07-06 08:10:10,983 DEBUG subiquitycore.utils:48 run_command called: ['cloud-init', 'status', '--wait'] 2021-07-06 08:10:15,543 DEBUG subiquitycore.utils:61 run_command ['cloud-init', 'status', '--wait'] exited with code 0 2021-07-06 08:10:15,543 DEBUG subiquity:121 waited 4.56057333946228s for cloud-init 2021-07-06 08:10:15,545 DEBUG subiquity:123 loading cloud config 2021-07-06 08:10:15,545 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg (quiet=False) 2021-07-06 08:10:15,545 DEBUG cloudinit.util:1289 Read 4017 bytes from /etc/cloud/cloud.cfg 2021-07-06 08:10:15,545 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 4017 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,557 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2021-07-06 08:10:15,557 DEBUG cloudinit.util:1289 Read 285 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2021-07-06 08:10:15,557 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 285 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,559 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2021-07-06 08:10:15,559 DEBUG cloudinit.util:1289 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2021-07-06 08:10:15,559 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,564 DEBUG cloudinit.util:1278 Reading from /run/cloud-init/cloud.cfg (quiet=False) 2021-07-06 08:10:15,564 DEBUG cloudinit.util:1289 Read 35 bytes from /run/cloud-init/cloud.cfg 2021-07-06 08:10:15,564 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,565 DEBUG cloudinit.subp:221 Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True) 2021-07-06 08:10:15,571 DEBUG cloudinit.util:1278 Reading from /etc/os-release (quiet=False) 2021-07-06 08:10:15,572 DEBUG cloudinit.util:1289 Read 382 bytes from /etc/os-release 2021-07-06 08:10:15,572 DEBUG cloudinit.util:1278 Reading from /proc/1/environ (quiet=False) 2021-07-06 08:10:15,572 DEBUG cloudinit.util:1289 Read 266 bytes from /proc/1/environ 2021-07-06 08:10:15,572 DEBUG cloudinit.util:1278 Reading from /proc/self/status (quiet=False) 2021-07-06 08:10:15,572 DEBUG cloudinit.util:1289 Read 1312 bytes from /proc/self/status 2021-07-06 08:10:15,573 DEBUG cloudinit.util:1278 Reading from /proc/cmdline (quiet=False) 2021-07-06 08:10:15,573 DEBUG cloudinit.util:1289 Read 37 bytes from /proc/cmdline 2021-07-06 08:10:15,573 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,573 DEBUG cloudinit.util:789 loaded blob returned None, returning default. 2021-07-06 08:10:15,573 DEBUG cloudinit.util:1278 Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2021-07-06 08:10:15,573 DEBUG cloudinit.util:1289 Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2021-07-06 08:10:15,573 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,573 DEBUG cloudinit.util:789 loaded blob returned None, returning default. 2021-07-06 08:10:15,574 DEBUG cloudinit.util:1278 Reading from /var/lib/cloud/instance/obj.pkl (quiet=False) 2021-07-06 08:10:15,574 DEBUG cloudinit.util:1289 Read 7376 bytes from /var/lib/cloud/instance/obj.pkl 2021-07-06 08:10:15,580 DEBUG cloudinit.util:1278 Reading from /run/cloud-init/.instance-id (quiet=False) 2021-07-06 08:10:15,580 DEBUG cloudinit.util:1289 Read 8 bytes from /run/cloud-init/.instance-id 2021-07-06 08:10:15,580 DEBUG cloudinit.stages:248 restored from cache with run check: DataSourceNoCloud [seed=/var/lib/cloud/seed/nocloud][dsmode=net] 2021-07-06 08:10:15,581 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg (quiet=False) 2021-07-06 08:10:15,581 DEBUG cloudinit.util:1289 Read 4017 bytes from /etc/cloud/cloud.cfg 2021-07-06 08:10:15,581 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 4017 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,590 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False) 2021-07-06 08:10:15,590 DEBUG cloudinit.util:1289 Read 285 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg 2021-07-06 08:10:15,590 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 285 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,591 DEBUG cloudinit.util:1278 Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False) 2021-07-06 08:10:15,591 DEBUG cloudinit.util:1289 Read 2070 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg 2021-07-06 08:10:15,592 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 2070 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,594 DEBUG cloudinit.util:1278 Reading from /run/cloud-init/cloud.cfg (quiet=False) 2021-07-06 08:10:15,594 DEBUG cloudinit.util:1289 Read 35 bytes from /run/cloud-init/cloud.cfg 2021-07-06 08:10:15,594 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 35 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,594 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,594 DEBUG cloudinit.util:789 loaded blob returned None, returning default. 2021-07-06 08:10:15,594 DEBUG cloudinit.util:1278 Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False) 2021-07-06 08:10:15,594 DEBUG cloudinit.util:1289 Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt 2021-07-06 08:10:15,595 DEBUG cloudinit.util:786 Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,) 2021-07-06 08:10:15,595 DEBUG cloudinit.util:789 loaded blob returned None, returning default. 2021-07-06 08:10:15,595 DEBUG cloudinit.stages:83 Using distro class <class 'cloudinit.distros.ubuntu.Distro'> 2021-07-06 08:10:15,597 DEBUG asyncio:54 Using selector: EpollSelector 2021-07-06 08:10:15,597 DEBUG subiquitycore.prober:35 Prober() init finished, data:None 2021-07-06 08:10:15,598 DEBUG subiquity.signals:50 connect_signal: network-proxy-set -> <function SubiquityServer.__init__.<locals>.<lambda> at 0x7f83e3514598> 2021-07-06 08:10:15,598 DEBUG subiquity.signals:50 connect_signal: network-change -> SubiquityServer._network_change 2021-07-06 08:10:15,599 DEBUG curtin:89 Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (capture=True) 2021-07-06 08:10:15,605 DEBUG subiquitycore.netplan:111 config for zz-all-en = {'dhcp4': True, 'match': {'name': 'en'}} 2021-07-06 08:10:15,605 DEBUG subiquitycore.netplan:111 config for zz-all-eth = {'dhcp4': True, 'match': {'name': 'eth'}} 2021-07-06 08:10:15,608 DEBUG subiquity.server.server:314 load_autoinstall_config only_early True 2021-07-06 08:10:15,609 DEBUG subiquity.server.server:314 load_autoinstall_config only_early False 2021-07-06 08:10:15,609 DEBUG subiquity.signals:50 connect_signal: snapd-network-change -> RefreshController.snapd_network_changed 2021-07-06 08:10:15,610 DEBUG subiquity.signals:50 connect_signal: snapd-network-change -> MirrorController.snapd_network_changed 2021-07-06 08:10:15,610 DEBUG subiquity.signals:50 connect_signal: snapd-network-change -> SnapListController.snapd_network_changed 2021-07-06 08:10:15,610 DEBUG subiquitycore.core:92 known signals: ['network-proxy-set', 'network-change', 'snapd-network-change'] 2021-07-06 08:10:15,610 DEBUG subiquitycore.core:120 starting controllers 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for link_change: NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for link_change: NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'eno1'} 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2021-07-06 08:10:15,613 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::de4a:3eff:fe9b:b2f1/64'} 2021-07-06 08:10:15,613 DEBUG probert.network:673 link_change NEW {'ifindex': 1, 'flags': 65609, 'arptype': 772, 'family': 0, 'is_vlan': False, 'name': b'lo'} 2021-07-06 08:10:15,656 DEBUG subiquitycore.models.network:422 new_link 1 lo lo 2021-07-06 08:10:15,656 DEBUG probert.network:673 link_change NEW {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'eno1'} 2021-07-06 08:10:15,697 DEBUG subiquitycore.models.network:422 new_link 2 eno1 eth 2021-07-06 08:10:15,697 DEBUG subiquitycore.models.network:450 new_link 2 eno1 with config {'dhcp4': True} 2021-07-06 08:10:15,697 DEBUG root:39 start: subiquity/Network/_send_update: NEW eno1 2021-07-06 08:10:15,698 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:15,698 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: NEW eno1 2021-07-06 08:10:15,698 DEBUG probert.network:718 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 2, 'scope': 254, 'local': b'127.0.0.1/8'} 2021-07-06 08:10:15,698 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:15,698 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:15,698 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:15,698 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:15,698 DEBUG probert.network:718 addr_change NEW {'ifindex': 1, 'flags': 128, 'family': 10, 'scope': 254, 'local': b'::1'} 2021-07-06 08:10:15,698 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 128, 'family': 10, 'scope': 253, 'local': b'fe80::de4a:3eff:fe9b:b2f1/64'} 2021-07-06 08:10:15,699 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:15,699 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:15,699 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:15,699 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:10:15,699 DEBUG subiquitycore.controller.network:93 default routes {2} 2021-07-06 08:10:15,700 DEBUG subiquity.server.controllers.snaplist:59 loading list of snaps 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.0.0.0', 'ifindex': 1} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.0/8', 'ifindex': 1} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'127.0.0.1', 'ifindex': 1} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'127.255.255.255', 'ifindex': 1} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'::1', 'ifindex': 1} 2021-07-06 08:10:15,700 DEBUG probert.network:732 route_change NEW {'family': 10, 'type': 1, 'table': 254, 'dst': b'fe80::/64', 'ifindex': 2} 2021-07-06 08:10:15,701 DEBUG probert.network:732 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'::1', 'ifindex': 1} 2021-07-06 08:10:15,701 DEBUG probert.network:732 route_change NEW {'family': 10, 'type': 2, 'table': 255, 'dst': b'fe80::de4a:3eff:fe9b:b2f1', 'ifindex': 2} 2021-07-06 08:10:15,701 DEBUG probert.network:732 route_change NEW {'family': 10, 'type': 1, 'table': 255, 'dst': b'ff00::/8', 'ifindex': 2} 2021-07-06 08:10:15,728 DEBUG subiquitycore.core:123 controllers started 2021-07-06 08:10:15,728 INFO root:39 start: subiquity/apply_autoinstall_config: 2021-07-06 08:10:15,728 DEBUG root:39 start: subiquity/Early/apply_autoinstall_config: 2021-07-06 08:10:15,736 DEBUG root:39 finish: subiquity/Early/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,737 DEBUG root:39 start: subiquity/Reporting/apply_autoinstall_config: 2021-07-06 08:10:15,737 DEBUG root:39 finish: subiquity/Reporting/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,737 DEBUG root:39 start: subiquity/Error/apply_autoinstall_config: 2021-07-06 08:10:15,737 DEBUG root:39 finish: subiquity/Error/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,737 DEBUG root:39 start: subiquity/Userdata/apply_autoinstall_config: 2021-07-06 08:10:15,737 DEBUG root:39 finish: subiquity/Userdata/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,738 DEBUG subiquity.models.subiquity:146 model userdata is configured, to go set() 2021-07-06 08:10:15,738 DEBUG root:39 start: subiquity/Package/apply_autoinstall_config: 2021-07-06 08:10:15,738 DEBUG root:39 finish: subiquity/Package/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,738 DEBUG subiquity.models.subiquity:146 model packages is configured, to go set() 2021-07-06 08:10:15,738 DEBUG root:39 start: subiquity/Debconf/apply_autoinstall_config: 2021-07-06 08:10:15,738 DEBUG root:39 finish: subiquity/Debconf/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,738 DEBUG subiquity.models.subiquity:146 model debconf_selections is configured, to go set() 2021-07-06 08:10:15,738 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Locale as interactive 2021-07-06 08:10:15,738 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Refresh as interactive 2021-07-06 08:10:15,738 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Keyboard as interactive 2021-07-06 08:10:15,738 DEBUG root:39 start: subiquity/Zdev/apply_autoinstall_config: 2021-07-06 08:10:15,738 DEBUG root:39 finish: subiquity/Zdev/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Network as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Proxy as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Mirror as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Filesystem as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Identity as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping SSH as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping SnapList as interactive 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Install as interactive 2021-07-06 08:10:15,739 DEBUG root:39 start: subiquity/Late/apply_autoinstall_config: 2021-07-06 08:10:15,739 DEBUG root:39 finish: subiquity/Late/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,739 DEBUG subiquity.server.server:308 apply_autoinstall_config: skipping Reboot as interactive 2021-07-06 08:10:15,739 INFO root:39 finish: subiquity/apply_autoinstall_config: SUCCESS: 2021-07-06 08:10:15,739 DEBUG root:39 start: subiquity/Refresh/configure_snapd: 2021-07-06 08:10:15,739 DEBUG root:39 start: subiquity/Refresh/configure_snapd/get_details: 2021-07-06 08:10:15,740 DEBUG root:39 start: subiquity/Refresh/check_for_update: 2021-07-06 08:10:15,740 DEBUG root:39 start: subiquity/Refresh/check_for_update: 2021-07-06 08:10:15,742 DEBUG root:39 start: subiquity/Mirror/lookup: 2021-07-06 08:10:15,743 DEBUG root:39 start: subiquity/SnapList/loader: 2021-07-06 08:10:15,743 DEBUG urllib3.connectionpool:826 Starting new HTTPS connection (1): geoip.ubuntu.com 2021-07-06 08:10:15,749 DEBUG root:39 start: subiquity/Install/install: 2021-07-06 08:10:15,749 ERROR root:39 finish: subiquity/Refresh/check_for_update: FAIL: cancelled 2021-07-06 08:10:15,749 DEBUG root:39 start: subiquity/SnapList/loader/list: 2021-07-06 08:10:15,750 DEBUG root:39 start: subiquity/Filesystem/_probe: 2021-07-06 08:10:15,751 DEBUG root:39 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2021-07-06 08:10:15,759 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/snaps/subiquity HTTP/1.1" 200 1175 2021-07-06 08:10:15,760 DEBUG root:39 finish: subiquity/Refresh/configure_snapd/get_details: SUCCESS: current version of snap is: '21.01.2' 2021-07-06 08:10:15,760 DEBUG root:39 start: subiquity/Refresh/configure_snapd/switching: switching subiquity to stable/ubuntu-20.04.2 2021-07-06 08:10:15,765 DEBUG urllib3.connectionpool:396 http://localhost:None "POST /v2/snaps/subiquity HTTP/1.1" 202 81 2021-07-06 08:10:15,768 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/changes/4 HTTP/1.1" 200 596 2021-07-06 08:10:15,769 DEBUG root:39 finish: subiquity/Refresh/configure_snapd/switching: SUCCESS: switched to stable/ubuntu-20.04.2 2021-07-06 08:10:15,769 DEBUG root:39 finish: subiquity/Refresh/configure_snapd: SUCCESS: 2021-07-06 08:10:15,792 DEBUG probert.multipath:49 Extracted multipath paths fields: ['sda', '4D4507640D2701095431', '[orphan]', '[undef]', 'ata-1.00', '[undef]', '[undef]', '[undef]'] 2021-07-06 08:10:15,792 DEBUG probert.dasd:131 Probing DASD devies 2021-07-06 08:10:15,792 DEBUG probert.dasd:134 DASD devices only present on s390x, arch=x86_64 2021-07-06 08:10:16,041 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:10:16,042 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2021-07-06 08:10:16,042 INFO aiohttp.access:206 [06/Jul/2021:08:10:16 +0000] "GET /meta/status HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:16,063 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:10:16,063 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "WAITING", "confirming_tty": "", "error": null, "cloud_init_ok": tr... 2021-07-06 08:10:16,064 INFO aiohttp.access:206 [06/Jul/2021:08:10:16 +0000] "GET /meta/status?cur=null HTTP/1.1" 200 411 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:16,064 DEBUG root:39 start: subiquity/Locale/GET: 2021-07-06 08:10:16,064 DEBUG root:39 finish: subiquity/Locale/GET: SUCCESS: 200 "C" 2021-07-06 08:10:16,065 INFO aiohttp.access:206 [06/Jul/2021:08:10:16 +0000] "GET /locale HTTP/1.1" 200 188 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:16,065 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:10:16,083 INFO probert.lvm:120 b' 1 logical volume(s) in volume group "ubuntu-vg" now active\n' 2021-07-06 08:10:16,256 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?section=server HTTP/1.1" 200 None 2021-07-06 08:10:16,257 DEBUG root:39 finish: subiquity/SnapList/loader/list: SUCCESS: 2021-07-06 08:10:16,258 DEBUG subiquity.server.controllers.snaplist:67 fetched list of 24 snaps 2021-07-06 08:10:16,259 DEBUG root:39 start: subiquity/SnapList/loader/fetch/microk8s: 2021-07-06 08:10:16,496 DEBUG curtin:1312 Extracting storage config from probe data 2021-07-06 08:10:16,605 DEBUG curtin:1319 Sorting extracted configurations 2021-07-06 08:10:16,605 INFO curtin:1338 Validating extracted storage config components 2021-07-06 08:10:16,620 DEBUG curtin:1357 Extracted (unmerged) storage config: storage: - id: disk-sdb path: /dev/sdb ptable: dos serial: USB_FLASH_DRIVE_B4CE8395-0:0 type: disk - id: disk-sda path: /dev/sda ptable: gpt serial: SATA_SSD_4D4507640D2701095431 type: disk wwn: '0x5000000000000000' - device: disk-sdb flag: boot id: partition-sdb1 number: 1 offset: 1048576 size: 7779385344 type: partition - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 size: 536870912 type: partition - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 537919488 size: 1073741824 type: partition - device: disk-sda flag: linux id: partition-sda3 number: 3 offset: 1611661312 size: 118420930560 type: partition - fstype: vfat id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: f90f0594-1f78-4dc3-99e4-26e6f2ac47ed volume: partition-sda2 - fstype: ext4 id: format-lvm-partition-ubuntu-lv type: format uuid: 950c70ab-7107-4714-a6c0-f113e1113bf6 volume: lvm-partition-ubuntu-lv - devices: - partition-sda3 id: lvm-volgroup-ubuntu-vg name: ubuntu-vg type: lvm_volgroup - id: lvm-partition-ubuntu-lv name: ubuntu-lv size: 59210989568B type: lvm_partition volgroup: lvm-volgroup-ubuntu-vg - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2021-07-06 08:10:16,620 DEBUG curtin:1359 Generating storage config dependencies 2021-07-06 08:10:16,620 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,620 DEBUG curtin:239 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,620 DEBUG curtin:239 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,620 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,620 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?select=refresh HTTP/1.1" 200 None 2021-07-06 08:10:16,620 DEBUG curtin:239 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: format-lvm-partition-ubuntu-lv:SourceType:format -> (DepId:lvm-partition-ubuntu-lv DepType:lvm_partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,621 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,622 DEBUG curtin:239 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2021-07-06 08:10:16,622 DEBUG curtin:239 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:10:16,622 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:10:16,622 DEBUG curtin:1365 Merging storage config dependencies 2021-07-06 08:10:16,625 DEBUG curtin:1372 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: SATA_SSD_4D4507640D2701095431 type: disk wwn: '0x5000000000000000' - id: disk-sdb path: /dev/sdb ptable: dos serial: USB_FLASH_DRIVE_B4CE8395-0:0 type: disk - device: disk-sdb flag: boot id: partition-sdb1 number: 1 offset: 1048576 size: 7779385344 type: partition - fstype: vfat id: format-partition-sdb1 type: format volume: partition-sdb1 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 size: 536870912 type: partition - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 537919488 size: 1073741824 type: partition - device: disk-sda flag: linux id: partition-sda3 number: 3 offset: 1611661312 size: 118420930560 type: partition - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: f90f0594-1f78-4dc3-99e4-26e6f2ac47ed volume: partition-sda2 - devices: - partition-sda3 id: lvm-volgroup-ubuntu-vg name: ubuntu-vg type: lvm_volgroup - id: lvm-partition-ubuntu-lv name: ubuntu-lv size: 59210989568B type: lvm_partition volgroup: lvm-volgroup-ubuntu-vg - fstype: ext4 id: format-lvm-partition-ubuntu-lv type: format uuid: 950c70ab-7107-4714-a6c0-f113e1113bf6 volume: lvm-partition-ubuntu-lv version: 1 2021-07-06 08:10:16,626 DEBUG subiquity.models.filesystem:1567 exclusions {'partition-sdb1', 'disk-sdb', 'format-partition-sdb1'} 2021-07-06 08:10:16,626 DEBUG root:39 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2021-07-06 08:10:16,626 DEBUG subiquity.server.controllers.refresh:179 check_for_update received {'type': 'sync', 'status-code': 200, 'status': 'OK', 'result': [{'id': 'CSO04Jhav2yK0uz97cr0ipQRyqg0qQL6', 'title': 'Core 18', 'summary': 'Runtime environment based on Ubuntu 18.04', 'description': 'The base snap based on the Ubuntu 18.04 release.', 'download-size': 58134528, 'name': 'core18', 'publisher': {'id': 'canonical', 'username': 'canonical', 'display-name': 'Canonical', 'validation': 'verified'}, 'developer': 'canonical', 'status': 'available', 'type': 'base', 'version': '20210611', 'channel': 'latest/stable', 'ignore-validation': False, 'revision': '2074', 'confinement': 'strict', 'private': False, 'devmode': False, 'jailmode': False, 'contact': '', 'license': 'Other Open Source'}, {'id': 'J60k4JY0HppjwOjW8dZdYc8obXKxujRu', 'title': 'LXD', 'summary': 'System container and virtual machine manager', 'description': "LXD is a system container and virtual machine manager\n\nWith LXD you can run hundreds of containers of a variety of Linux \ndistributions, apply resource limits, pass in directories, USB devices\nor GPUs and setup any network and storage you want.\n\nLXD containers are lightweight, secure by default and a great \nalternative to running Linux virtual machines.\n\nIf you want to run other Operating Systems or special Linux workloads,\nyou can use LXD virtual machines instead\n\nRun any Linux distribution you want\n\nPre-made images are available for Ubuntu, Alpine Linux, ArchLinux, \nCentOS, Debian, Fedora, Gentoo, OpenSUSE and more.\n\nA full list of available images can be found here: https://images.linuxcontainers.org\n\nCan't find the distribution you want? It's easy to make your own images too, either using our `distrobuilder` tool or by assembling your own image tarball by hand.\n\n\nContainers and VMs at scale\n\nLXD is network aware and all interactions go through a simple REST API, \nmaking it possible to remotely interact with instances on remote\nsystems, copying and moving them as you wish.\n\nWant to go big? LXD also has built-in clustering support,\nletting you turn dozens of servers into one big LXD server.\n\n\nConfiguration options\n\nSupported options for the LXD snap (`snap set lxd KEY=VALUE`):\n - ceph.builtin: Use snap-specific Ceph configuration [default=false]\n - ceph.external: Use the system's ceph tools (ignores ceph.builtin) [default=false]\n - criu.enable: Enable experimental live-migration support [default=false]\n - daemon.debug: Increase logging to debug level [default=false]\n - daemon.group: Set group of users that can interact with LXD [default=lxd]\n - daemon.preseed: Pass a YAML configuration to `lxd init` on initial start\n - daemon.syslog: Send LXD log events to syslog [default=false]\n - lvm.external: Use the system's LVM tools [default=false]\n - lxcfs.pidfd: Start per-container process tracking [default=false]\n - lxcfs.loadavg: Start tracking per-container load average [default=false]\n - lxcfs.cfs: Consider CPU shares for CPU usage [default=false]\n - openvswitch.builtin: Run a snap-specific OVS daemon [default=false]\n - shiftfs.enable: Enable shiftfs support [default=auto]\n\nDocumentation: https://linuxcontainers.org/lxd/docs/master/", 'download-size': 70848512, 'icon': 'https://dashboard.snapcraft.io/site_media/appmedia/2016/08/containers.png', 'name': 'lxd', 'publisher': {'id': 'canonical', 'username': 'canonical', 'display-name': 'Canonical', 'validation': 'verified'}, 'developer': 'canonical', 'status': 'available', 'type': 'app', 'base': 'core18', 'version': '4.0.6', 'channel': '4.0/stable', 'ignore-validation': False, 'revision': '20326', 'confinement': 'strict', 'private': False, 'devmode': False, 'jailmode': False, 'contact': 'https://github.com/lxc/lxd/issues', 'license': 'Apache-2.0', 'website': 'https://linuxcontainers.org/lxd', 'media': [{'type': 'icon', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2016/08/containers.png', 'width': 256, 'height': 256}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2018/10/Screenshot_from_2018-10-26_12-57-24.png', 'width': 977, 'height': 592}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2018/10/Screenshot_from_2018-10-26_14-20-14.png', 'width': 1003, 'height': 592}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2018/10/Screenshot_from_2018-10-26_14-21-43.png', 'width': 1003, 'height': 592}, {'type': 'video', 'url': 'https://asciinema.org/a/226224'}]}, {'id': 'PMrrV4ml8uWuEUDBT8dSGnKUYbevVhc4', 'title': 'snapd', 'summary': 'Background service that manages and maintains installed snaps', 'description': "Note: you do not need to install _snapd_ manually if you already have _snap_ installed.\n\nSnaps are app packages for desktop, cloud and IoT that update automatically, are easy to install, secure, cross-platform and dependency-free. They're being used on millions of Linux systems every day.\n\nAlongside its various service and management functions, snapd:\n- provides the _snap_ command that's used to install and remove snaps and interact with the wider snap ecosystem\n- implements the confinement policies that isolate snaps from the base system and from each other\n- governs the interfaces that allow snaps to access specific system resources outside of their confinement\n\nFor general details, including _installation_ and _Getting started_ guides, head over to our _Snap documentation_: https://snapcraft.io/docs\n\nIf you want to build your own snaps, start with our _Creating a snap_ documentation: https://snapcraft.io/docs/creating-a-snap\n\nGet involved\n\nThis is an _open source_ project and we warmly welcome community contributions, suggestions, and constructive feedback. If you're interested in contributing, please take a look at our _Code of Conduct_ first.\n\n- to report an issue, please file _a bug report_ (https://bugs.launchpad.net/snappy/+filebug) on our _Launchpad issue tracker_ (https://bugs.launchpad.net/snappy/)\n- for suggestions and constructive feedback, create a post on the _Snapcraft forum_ (https://forum.snapcraft.io/c/snapd)\n- to build snapd manually, or to get started with snapd development, see _HACKING.md (https://raw.githubusercontent.com/snapcore/snapd/master/HACKING.md)\n\nGet in touch\n\nWe're friendly! We have a community forum at https://forum.snapcraft.io where we discuss feature plans, development news, issues, updates and troubleshooting. You can chat in realtime with the snapd team and our wider community on the _#snappy_ IRC channel on _libera.chat_ (https://libera.chat/).\n\nFor news and updates, follow us on _Twitter_ (https://twitter.com/snapcraftio) and on _Facebook_ (https://www.facebook.com/snapcraftio).\n\nThe snapd source code can be found on GitHub: https://github.com/snapcore/snapd", 'download-size': 33853440, 'icon': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/snapd.png', 'name': 'snapd', 'publisher': {'id': 'canonical', 'username': 'canonical', 'display-name': 'Canonical', 'validation': 'verified'}, 'developer': 'canonical', 'status': 'available', 'type': 'snapd', 'version': '2.51.1', 'channel': 'latest/stable', 'ignore-validation': False, 'revision': '12398', 'confinement': 'strict', 'private': False, 'devmode': False, 'jailmode': False, 'contact': '', 'license': 'GPL-3.0+', 'website': 'https://snapcraft.io', 'media': [{'type': 'icon', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/snapd.png', 'width': 460, 'height': 460}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/Screenshot_20190924_115756_hLcyetO.png', 'width': 956, 'height': 648}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/Screenshot_20190924_115824_2v3y6l8.png', 'width': 956, 'height': 648}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/Screenshot_20190924_115055_Uuq7KIb.png', 'width': 1023, 'height': 834}, {'type': 'screenshot', 'url': 'https://dashboard.snapcraft.io/site_media/appmedia/2019/09/Screenshot_20190924_125944.png', 'width': 956, 'height': 648}]}]} 2021-07-06 08:10:16,626 DEBUG root:39 finish: subiquity/Refresh/check_for_update: SUCCESS: no new version of snap available 2021-07-06 08:10:16,626 DEBUG root:39 finish: subiquity/Filesystem/_probe: SUCCESS: 2021-07-06 08:10:16,811 DEBUG urllib3.connectionpool:396 https://geoip.ubuntu.com:443 "GET /lookup HTTP/1.1" 200 None 2021-07-06 08:10:16,815 DEBUG root:39 finish: subiquity/Mirror/lookup: SUCCESS: 2021-07-06 08:10:17,270 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=microk8s HTTP/1.1" 200 None 2021-07-06 08:10:17,280 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/microk8s: SUCCESS: 2021-07-06 08:10:17,280 DEBUG root:39 start: subiquity/SnapList/loader/fetch/nextcloud: 2021-07-06 08:10:17,979 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=nextcloud HTTP/1.1" 200 None 2021-07-06 08:10:17,983 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/nextcloud: SUCCESS: 2021-07-06 08:10:17,983 DEBUG root:39 start: subiquity/SnapList/loader/fetch/wekan: 2021-07-06 08:10:18,349 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=wekan HTTP/1.1" 200 None 2021-07-06 08:10:18,351 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/wekan: SUCCESS: 2021-07-06 08:10:18,352 DEBUG root:39 start: subiquity/SnapList/loader/fetch/kata-containers: 2021-07-06 08:10:18,691 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=kata-containers HTTP/1.1" 200 None 2021-07-06 08:10:18,693 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/kata-containers: SUCCESS: 2021-07-06 08:10:18,694 DEBUG root:39 start: subiquity/SnapList/loader/fetch/docker: 2021-07-06 08:10:19,068 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=docker HTTP/1.1" 200 None 2021-07-06 08:10:19,071 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/docker: SUCCESS: 2021-07-06 08:10:19,071 DEBUG root:39 start: subiquity/SnapList/loader/fetch/canonical-livepatch: 2021-07-06 08:10:19,400 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=canonical-livepatch HTTP/1.1" 200 1851 2021-07-06 08:10:19,402 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/canonical-livepatch: SUCCESS: 2021-07-06 08:10:19,403 DEBUG root:39 start: subiquity/SnapList/loader/fetch/rocketchat-server: 2021-07-06 08:10:19,762 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=rocketchat-server HTTP/1.1" 200 None 2021-07-06 08:10:19,765 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/rocketchat-server: SUCCESS: 2021-07-06 08:10:19,765 DEBUG root:39 start: subiquity/SnapList/loader/fetch/mosquitto: 2021-07-06 08:10:20,147 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=mosquitto HTTP/1.1" 200 None 2021-07-06 08:10:20,149 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/mosquitto: SUCCESS: 2021-07-06 08:10:20,149 DEBUG root:39 start: subiquity/SnapList/loader/fetch/etcd: 2021-07-06 08:10:20,821 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=etcd HTTP/1.1" 200 None 2021-07-06 08:10:20,824 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/etcd: SUCCESS: 2021-07-06 08:10:20,825 DEBUG root:39 start: subiquity/SnapList/loader/fetch/powershell: 2021-07-06 08:10:21,193 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=powershell HTTP/1.1" 200 None 2021-07-06 08:10:21,195 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/powershell: SUCCESS: 2021-07-06 08:10:21,196 DEBUG root:39 start: subiquity/SnapList/loader/fetch/stress-ng: 2021-07-06 08:10:21,608 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=stress-ng HTTP/1.1" 200 None 2021-07-06 08:10:21,610 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/stress-ng: SUCCESS: 2021-07-06 08:10:21,610 DEBUG root:39 start: subiquity/SnapList/loader/fetch/sabnzbd: 2021-07-06 08:10:21,976 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=sabnzbd HTTP/1.1" 200 None 2021-07-06 08:10:21,978 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/sabnzbd: SUCCESS: 2021-07-06 08:10:21,979 DEBUG root:39 start: subiquity/SnapList/loader/fetch/wormhole: 2021-07-06 08:10:22,189 DEBUG root:39 start: subiquity/Locale/POST: "en_US" 2021-07-06 08:10:22,189 DEBUG subiquity.models.subiquity:146 model locale is configured, to go set() 2021-07-06 08:10:22,189 DEBUG root:39 finish: subiquity/Locale/POST: SUCCESS: 200 null 2021-07-06 08:10:22,190 INFO aiohttp.access:206 [06/Jul/2021:08:10:22 +0000] "POST /locale HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:22,190 DEBUG root:39 start: subiquity/Refresh/GET: 2021-07-06 08:10:22,191 DEBUG root:39 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "21.01.2", "new_snap_... 2021-07-06 08:10:22,191 INFO aiohttp.access:206 [06/Jul/2021:08:10:22 +0000] "GET /refresh HTTP/1.1" 200 276 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:22,192 DEBUG root:39 start: subiquity/Keyboard/GET: 2021-07-06 08:10:22,192 DEBUG root:39 finish: subiquity/Keyboard/GET: SUCCESS: 200 {"layout": "us", "variant": "", "toggle": null} 2021-07-06 08:10:22,192 INFO aiohttp.access:206 [06/Jul/2021:08:10:22 +0000] "GET /keyboard HTTP/1.1" 200 233 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:22,391 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=wormhole HTTP/1.1" 200 None 2021-07-06 08:10:22,393 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/wormhole: SUCCESS: 2021-07-06 08:10:22,394 DEBUG root:39 start: subiquity/SnapList/loader/fetch/aws-cli: 2021-07-06 08:10:22,746 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=aws-cli HTTP/1.1" 200 1873 2021-07-06 08:10:22,748 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/aws-cli: SUCCESS: 2021-07-06 08:10:22,749 DEBUG root:39 start: subiquity/SnapList/loader/fetch/google-cloud-sdk: 2021-07-06 08:10:23,112 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=google-cloud-sdk HTTP/1.1" 200 1732 2021-07-06 08:10:23,114 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/google-cloud-sdk: SUCCESS: 2021-07-06 08:10:23,115 DEBUG root:39 start: subiquity/SnapList/loader/fetch/slcli: 2021-07-06 08:10:23,454 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=slcli HTTP/1.1" 200 1898 2021-07-06 08:10:23,456 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/slcli: SUCCESS: 2021-07-06 08:10:23,457 DEBUG root:39 start: subiquity/SnapList/loader/fetch/doctl: 2021-07-06 08:10:23,819 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=doctl HTTP/1.1" 200 1968 2021-07-06 08:10:23,821 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/doctl: SUCCESS: 2021-07-06 08:10:23,822 DEBUG root:39 start: subiquity/SnapList/loader/fetch/conjure-up: 2021-07-06 08:10:24,164 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=conjure-up HTTP/1.1" 200 None 2021-07-06 08:10:24,166 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/conjure-up: SUCCESS: 2021-07-06 08:10:24,167 DEBUG root:39 start: subiquity/SnapList/loader/fetch/minidlna-escoand: 2021-07-06 08:10:24,519 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=minidlna-escoand HTTP/1.1" 200 1992 2021-07-06 08:10:24,521 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/minidlna-escoand: SUCCESS: 2021-07-06 08:10:24,521 DEBUG root:39 start: subiquity/SnapList/loader/fetch/postgresql10: 2021-07-06 08:10:27,839 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=postgresql10 HTTP/1.1" 200 1450 2021-07-06 08:10:27,841 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/postgresql10: SUCCESS: 2021-07-06 08:10:27,842 DEBUG root:39 start: subiquity/SnapList/loader/fetch/heroku: 2021-07-06 08:10:28,964 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=heroku HTTP/1.1" 200 None 2021-07-06 08:10:28,966 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/heroku: SUCCESS: 2021-07-06 08:10:28,966 DEBUG root:39 start: subiquity/SnapList/loader/fetch/keepalived: 2021-07-06 08:10:29,323 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=keepalived HTTP/1.1" 200 None 2021-07-06 08:10:29,325 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/keepalived: SUCCESS: 2021-07-06 08:10:29,326 DEBUG root:39 start: subiquity/SnapList/loader/fetch/prometheus: 2021-07-06 08:10:29,777 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=prometheus HTTP/1.1" 200 None 2021-07-06 08:10:29,780 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/prometheus: SUCCESS: 2021-07-06 08:10:29,780 DEBUG root:39 start: subiquity/SnapList/loader/fetch/juju: 2021-07-06 08:10:30,344 DEBUG urllib3.connectionpool:396 http://localhost:None "GET /v2/find?name=juju HTTP/1.1" 200 None 2021-07-06 08:10:30,347 DEBUG root:39 finish: subiquity/SnapList/loader/fetch/juju: SUCCESS: 2021-07-06 08:10:30,347 DEBUG root:39 finish: subiquity/SnapList/loader: SUCCESS: 2021-07-06 08:10:32,701 DEBUG root:39 start: subiquity/Keyboard/POST: {"layout": "us", "variant": "", "toggle": null} 2021-07-06 08:10:32,701 DEBUG subiquity.models.subiquity:146 model keyboard is configured, to go set() 2021-07-06 08:10:32,701 DEBUG root:39 finish: subiquity/Keyboard/POST: SUCCESS: 200 null 2021-07-06 08:10:32,701 INFO aiohttp.access:206 [06/Jul/2021:08:10:32 +0000] "POST /keyboard HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:32,703 INFO aiohttp.access:206 [06/Jul/2021:08:10:32 +0000] "GET /zdev HTTP/1.1" 200 180 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:32,704 DEBUG root:39 start: subiquity/Network/GET: 2021-07-06 08:10:32,705 DEBUG root:39 finish: subiquity/Network/GET: SUCCESS: 200 [{"name": "eno1", "type": "eth", "is_connected": true, "bond_master": null, "... 2021-07-06 08:10:32,705 INFO aiohttp.access:206 [06/Jul/2021:08:10:32 +0000] "GET /network HTTP/1.1" 200 875 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:32,705 INFO root:39 start: subiquity/Network/apply_config: silent=True 2021-07-06 08:10:32,706 DEBUG subiquitycore.controller.network:240 network config: network: ethernets: eno1: dhcp4: true version: 2 2021-07-06 08:10:32,709 DEBUG subiquitycore.netplan:111 config for eno1 = {'dhcp4': True} 2021-07-06 08:10:32,709 DEBUG subiquitycore.utils:74 arun_command called: ['netplan', 'apply'] 2021-07-06 08:10:32,714 DEBUG root:39 start: subiquity/Network/subscription_PUT: 2021-07-06 08:10:32,715 DEBUG subiquity.server.controllers.network:218 added subscription /tmp/tmpatm93tqs/socket 2021-07-06 08:10:32,716 DEBUG root:39 finish: subiquity/Network/subscription_PUT: SUCCESS: 200 null 2021-07-06 08:10:32,716 INFO aiohttp.access:206 [06/Jul/2021:08:10:32 +0000] "PUT /network/subscription?socket_path=%22/tmp/tmpatm93tqs/socket%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:32,716 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:10:33,048 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:10:33,064 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:10:33,064 DEBUG probert.network:586 event for addr_change: DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:33,064 DEBUG probert.network:586 event for link_change: CHANGE {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'eno1'} 2021-07-06 08:10:33,064 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:10:33,064 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:10:33,064 DEBUG subiquitycore.controller.network:93 default routes set() 2021-07-06 08:10:33,064 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:10:33,065 DEBUG probert.network:718 addr_change DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:33,065 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:33,065 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:33,065 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:33,065 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:33,065 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:10:33,065 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:10:33,065 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:10:33,065 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:10:33,065 DEBUG probert.network:673 link_change CHANGE {'ifindex': 2, 'flags': 69699, 'arptype': 1, 'family': 0, 'is_vlan': False, 'name': b'eno1'} 2021-07-06 08:10:33,065 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:33,065 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:33,065 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:33,065 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:33,065 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:10:33,068 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:33,071 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:33,155 DEBUG subiquitycore.utils:83 arun_command ['netplan', 'apply'] exited with code 0 2021-07-06 08:10:33,155 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:10:33,167 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 1 2021-07-06 08:10:33,167 DEBUG subiquitycore.controller.network:178 waiting 0.1 to let udev event queue settle 2021-07-06 08:10:33,269 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:10:33,283 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:10:33,284 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:33,285 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:33,286 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:33,287 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:33,287 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:33,288 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:33,288 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:10:33,288 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:10:33,288 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:10:33,289 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:10:33,289 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:10:33,289 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:10:33,289 DEBUG subiquitycore.controller.network:93 default routes {2} 2021-07-06 08:10:33,289 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:10:33,290 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:33,295 INFO root:39 finish: subiquity/Network/apply_config: SUCCESS: silent=True 2021-07-06 08:10:33,298 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,257 DEBUG root:39 start: subiquity/Network/enable_dhcp_POST: 2021-07-06 08:10:46,257 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:46,257 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:46,257 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:46,258 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:46,258 DEBUG root:39 finish: subiquity/Network/enable_dhcp_POST: SUCCESS: 200 null 2021-07-06 08:10:46,258 INFO aiohttp.access:206 [06/Jul/2021:08:10:46 +0000] "POST /network/enable_dhcp?dev_name=%22eno1%22&ip_version=4 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:10:46,258 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,259 INFO root:39 start: subiquity/Network/apply_config: silent=False 2021-07-06 08:10:46,259 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:46,259 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:46,259 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:46,259 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:46,260 DEBUG subiquitycore.controller.network:240 network config: network: ethernets: eno1: dhcp4: true version: 2 2021-07-06 08:10:46,263 DEBUG subiquitycore.netplan:111 config for eno1 = {'dhcp4': True} 2021-07-06 08:10:46,263 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_starting 2021-07-06 08:10:46,263 DEBUG subiquitycore.utils:74 arun_command called: ['netplan', 'apply'] 2021-07-06 08:10:46,268 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,293 DEBUG subiquity.server.controllers.network:238 _call_client apply_starting /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,600 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:10:46,623 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:10:46,623 DEBUG probert.network:586 event for addr_change: DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:46,623 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:10:46,624 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:10:46,624 DEBUG subiquitycore.controller.network:93 default routes set() 2021-07-06 08:10:46,624 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:10:46,624 DEBUG probert.network:718 addr_change DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:46,624 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:46,624 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:46,624 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:46,624 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:46,624 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:10:46,624 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:10:46,624 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:10:46,624 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:10:46,625 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,628 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,711 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:10:46,736 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:10:46,737 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:46,738 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:10:46,739 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:10:46,739 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:10:46,740 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:10:46,740 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:10:46,740 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:10:46,741 DEBUG subiquitycore.controller.network:93 default routes {2} 2021-07-06 08:10:46,742 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:10:46,742 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,747 DEBUG subiquitycore.utils:83 arun_command ['netplan', 'apply'] exited with code 0 2021-07-06 08:10:46,747 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_stopping 2021-07-06 08:10:46,748 INFO root:39 finish: subiquity/Network/apply_config: SUCCESS: silent=False 2021-07-06 08:10:46,750 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:10:46,765 DEBUG subiquity.server.controllers.network:238 _call_client apply_stopping /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,064 DEBUG root:39 start: subiquity/Network/enable_dhcp_POST: 2021-07-06 08:11:30,065 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:11:30,065 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:11:30,065 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:11:30,066 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:11:30,066 DEBUG root:39 finish: subiquity/Network/enable_dhcp_POST: SUCCESS: 200 null 2021-07-06 08:11:30,067 INFO aiohttp.access:206 [06/Jul/2021:08:11:30 +0000] "POST /network/enable_dhcp?dev_name=%22eno1%22&ip_version=4 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:11:30,067 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,069 INFO root:39 start: subiquity/Network/apply_config: silent=False 2021-07-06 08:11:30,069 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:11:30,069 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:11:30,069 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:11:30,069 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:11:30,070 DEBUG subiquitycore.controller.network:240 network config: network: ethernets: eno1: dhcp4: true version: 2 2021-07-06 08:11:30,072 DEBUG subiquitycore.netplan:111 config for eno1 = {'dhcp4': True} 2021-07-06 08:11:30,072 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_starting 2021-07-06 08:11:30,072 DEBUG subiquitycore.utils:74 arun_command called: ['netplan', 'apply'] 2021-07-06 08:11:30,077 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,083 DEBUG subiquity.server.controllers.network:238 _call_client apply_starting /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,404 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:11:30,411 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:11:30,411 DEBUG probert.network:586 event for addr_change: DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:11:30,412 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:11:30,412 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:11:30,412 DEBUG subiquitycore.controller.network:93 default routes set() 2021-07-06 08:11:30,412 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:11:30,412 DEBUG probert.network:718 addr_change DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:11:30,414 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:11:30,414 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:11:30,414 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:11:30,415 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:11:30,415 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:11:30,415 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:11:30,415 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:11:30,415 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:11:30,416 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,418 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,551 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:11:30,563 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:11:30,563 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:11:30,563 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:11:30,564 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:11:30,564 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:11:30,564 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:11:30,564 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:11:30,564 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:11:30,565 DEBUG subiquitycore.controller.network:93 default routes {2} 2021-07-06 08:11:30,565 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:11:30,565 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,566 DEBUG subiquitycore.utils:83 arun_command ['netplan', 'apply'] exited with code 0 2021-07-06 08:11:30,567 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_stopping 2021-07-06 08:11:30,567 INFO root:39 finish: subiquity/Network/apply_config: SUCCESS: silent=False 2021-07-06 08:11:30,568 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:11:30,569 DEBUG subiquity.server.controllers.network:238 _call_client apply_stopping /tmp/tmpatm93tqs/socket 2021-07-06 08:12:19,571 DEBUG root:39 start: subiquity/Network/enable_dhcp_POST: 2021-07-06 08:12:19,571 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:12:19,571 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:12:19,572 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:12:19,572 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:12:19,572 DEBUG root:39 finish: subiquity/Network/enable_dhcp_POST: SUCCESS: 200 null 2021-07-06 08:12:19,573 INFO aiohttp.access:206 [06/Jul/2021:08:12:19 +0000] "POST /network/enable_dhcp?dev_name=%22eno1%22&ip_version=4 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:19,573 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:12:19,575 INFO root:39 start: subiquity/Network/apply_config: silent=False 2021-07-06 08:12:19,575 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:12:19,575 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:12:19,575 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:12:19,575 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:12:19,576 DEBUG subiquitycore.controller.network:240 network config: network: ethernets: eno1: dhcp4: true version: 2 2021-07-06 08:12:19,578 DEBUG subiquitycore.netplan:111 config for eno1 = {'dhcp4': True} 2021-07-06 08:12:19,578 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_starting 2021-07-06 08:12:19,578 DEBUG subiquitycore.utils:74 arun_command called: ['netplan', 'apply'] 2021-07-06 08:12:19,584 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:12:19,609 DEBUG subiquity.server.controllers.network:238 _call_client apply_starting /tmp/tmpatm93tqs/socket 2021-07-06 08:12:19,896 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:12:19,907 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:12:19,908 DEBUG probert.network:586 event for addr_change: DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:12:19,908 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:12:19,908 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:12:19,908 DEBUG subiquitycore.controller.network:93 default routes set() 2021-07-06 08:12:19,908 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:12:19,908 DEBUG probert.network:718 addr_change DEL {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:12:19,909 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:12:19,909 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:12:19,909 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:12:19,909 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:12:19,909 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:12:19,909 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:12:19,909 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:12:19,909 DEBUG probert.network:732 route_change DEL {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:12:19,909 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:12:19,911 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:12:20,010 DEBUG subiquitycore.utils:83 arun_command ['netplan', 'apply'] exited with code 0 2021-07-06 08:12:20,010 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket apply_stopping 2021-07-06 08:12:20,011 DEBUG subiquity.server.controllers.network:238 _call_client apply_stopping /tmp/tmpatm93tqs/socket 2021-07-06 08:12:20,015 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:12:20,035 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 1 2021-07-06 08:12:20,035 DEBUG subiquitycore.controller.network:178 waiting 0.1 to let udev event queue settle 2021-07-06 08:12:20,137 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:12:20,150 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:12:20,151 DEBUG probert.network:586 event for addr_change: NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:12:20,151 DEBUG probert.network:718 addr_change NEW {'ifindex': 2, 'flags': 0, 'family': 2, 'scope': 0, 'local': b'192.168.50.76/24'} 2021-07-06 08:12:20,153 DEBUG root:39 start: subiquity/Network/_send_update: CHANGE eno1 2021-07-06 08:12:20,153 DEBUG subiquity.server.controllers.network:269 dev_info eno1 {'dhcp4': True} 2021-07-06 08:12:20,154 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket update_link 2021-07-06 08:12:20,154 DEBUG root:39 finish: subiquity/Network/_send_update: SUCCESS: CHANGE eno1 2021-07-06 08:12:20,154 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 2, 'table': 255, 'dst': b'192.168.50.76', 'ifindex': 2} 2021-07-06 08:12:20,154 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.255', 'ifindex': 2} 2021-07-06 08:12:20,155 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.0/24', 'ifindex': 2} 2021-07-06 08:12:20,155 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 3, 'table': 255, 'dst': b'192.168.50.0', 'ifindex': 2} 2021-07-06 08:12:20,155 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'192.168.50.1', 'ifindex': 2} 2021-07-06 08:12:20,155 DEBUG probert.network:732 route_change NEW {'family': 2, 'type': 1, 'table': 254, 'dst': b'default', 'ifindex': 2} 2021-07-06 08:12:20,155 DEBUG subiquitycore.controller.network:93 default routes {2} 2021-07-06 08:12:20,156 DEBUG subiquity.server.controllers.network:246 creating _call_client task /tmp/tmpatm93tqs/socket route_watch 2021-07-06 08:12:20,156 DEBUG subiquity.server.controllers.network:238 _call_client update_link /tmp/tmpatm93tqs/socket 2021-07-06 08:12:20,160 INFO root:39 finish: subiquity/Network/apply_config: SUCCESS: silent=False 2021-07-06 08:12:20,164 DEBUG subiquity.server.controllers.network:238 _call_client route_watch /tmp/tmpatm93tqs/socket 2021-07-06 08:12:26,287 DEBUG root:39 start: subiquity/Network/POST: 2021-07-06 08:12:26,288 DEBUG subiquity.models.subiquity:146 model network is configured, to go set() 2021-07-06 08:12:26,288 DEBUG root:39 finish: subiquity/Network/POST: SUCCESS: 200 null 2021-07-06 08:12:26,289 INFO aiohttp.access:206 [06/Jul/2021:08:12:26 +0000] "POST /network HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:26,292 DEBUG root:39 start: subiquity/Proxy/GET: 2021-07-06 08:12:26,292 DEBUG root:39 finish: subiquity/Proxy/GET: SUCCESS: 200 "" 2021-07-06 08:12:26,293 INFO aiohttp.access:206 [06/Jul/2021:08:12:26 +0000] "GET /proxy HTTP/1.1" 200 187 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:26,295 DEBUG root:39 start: subiquity/Network/subscription_DELETE: 2021-07-06 08:12:26,295 DEBUG subiquity.server.controllers.network:231 removed subscription /tmp/tmpatm93tqs/socket 2021-07-06 08:12:26,296 DEBUG root:39 finish: subiquity/Network/subscription_DELETE: SUCCESS: 200 null 2021-07-06 08:12:26,296 INFO aiohttp.access:206 [06/Jul/2021:08:12:26 +0000] "DELETE /network/subscription?socket_path=%22/tmp/tmpatm93tqs/socket%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:28,093 DEBUG root:39 start: subiquity/Proxy/POST: "" 2021-07-06 08:12:28,094 DEBUG subiquity.models.subiquity:146 model proxy is configured, to go set() 2021-07-06 08:12:28,095 DEBUG root:39 finish: subiquity/Proxy/POST: SUCCESS: 200 null 2021-07-06 08:12:28,095 INFO aiohttp.access:206 [06/Jul/2021:08:12:28 +0000] "POST /proxy HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:28,096 DEBUG subiquitycore.snapd:58 restarting snapd to pick up proxy config 2021-07-06 08:12:28,097 DEBUG subiquitycore.utils:48 run_command called: ['systemctl', 'daemon-reload'] 2021-07-06 08:12:28,099 DEBUG root:39 start: subiquity/Mirror/GET: 2021-07-06 08:12:28,099 DEBUG root:39 finish: subiquity/Mirror/GET: SUCCESS: 200 "http://tw.archive.ubuntu.com/ubuntu" 2021-07-06 08:12:28,100 INFO aiohttp.access:206 [06/Jul/2021:08:12:28 +0000] "GET /mirror HTTP/1.1" 200 223 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:12:28,281 DEBUG subiquitycore.utils:61 run_command ['systemctl', 'daemon-reload'] exited with code 0 2021-07-06 08:12:28,281 DEBUG subiquitycore.utils:48 run_command called: ['systemctl', 'restart', 'snapd.service'] 2021-07-06 08:12:28,385 DEBUG subiquitycore.utils:61 run_command ['systemctl', 'restart', 'snapd.service'] exited with code 0 2021-07-06 08:12:28,387 DEBUG subiquitycore.utils:48 run_command called: ['udevadm', 'settle', '-t', '0'] 2021-07-06 08:12:28,415 DEBUG subiquitycore.utils:61 run_command ['udevadm', 'settle', '-t', '0'] exited with code 0 2021-07-06 08:12:28,416 DEBUG subiquity.server.controller.filesystem:245 _udev_event change Device('/sys/devices/virtual/block/loop7') 2021-07-06 08:12:28,416 DEBUG subiquity.server.controller.filesystem:245 _udev_event change Device('/sys/devices/virtual/block/loop7') 2021-07-06 08:12:28,416 DEBUG root:39 start: subiquity/Filesystem/_probe: 2021-07-06 08:12:28,417 DEBUG root:39 start: subiquity/Filesystem/_probe/probe_once: restricted=False 2021-07-06 08:12:28,446 DEBUG probert.multipath:49 Extracted multipath paths fields: ['sda', '4D4507640D2701095431', '[orphan]', '[undef]', 'ata-1.00', '[undef]', '[undef]', '[undef]'] 2021-07-06 08:12:28,447 DEBUG probert.dasd:131 Probing DASD devies 2021-07-06 08:12:28,447 DEBUG probert.dasd:134 DASD devices only present on s390x, arch=x86_64 2021-07-06 08:12:28,700 INFO probert.lvm:120 b' 1 logical volume(s) in volume group "ubuntu-vg" now active\n' 2021-07-06 08:12:29,095 DEBUG curtin:1312 Extracting storage config from probe data 2021-07-06 08:12:29,222 DEBUG curtin:1319 Sorting extracted configurations 2021-07-06 08:12:29,222 INFO curtin:1338 Validating extracted storage config components 2021-07-06 08:12:29,236 DEBUG curtin:1357 Extracted (unmerged) storage config: storage: - id: disk-sdb path: /dev/sdb ptable: dos serial: USB_FLASH_DRIVE_B4CE8395-0:0 type: disk - id: disk-sda path: /dev/sda ptable: gpt serial: SATA_SSD_4D4507640D2701095431 type: disk wwn: '0x5000000000000000' - device: disk-sdb flag: boot id: partition-sdb1 number: 1 offset: 1048576 size: 7779385344 type: partition - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 size: 536870912 type: partition - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 537919488 size: 1073741824 type: partition - device: disk-sda flag: linux id: partition-sda3 number: 3 offset: 1611661312 size: 118420930560 type: partition - fstype: vfat id: format-partition-sdb1 type: format volume: partition-sdb1 - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: f90f0594-1f78-4dc3-99e4-26e6f2ac47ed volume: partition-sda2 - fstype: ext4 id: format-lvm-partition-ubuntu-lv type: format uuid: 950c70ab-7107-4714-a6c0-f113e1113bf6 volume: lvm-partition-ubuntu-lv - devices: - partition-sda3 id: lvm-volgroup-ubuntu-vg name: ubuntu-vg type: lvm_volgroup - id: lvm-partition-ubuntu-lv name: ubuntu-lv size: 59210989568B type: lvm_partition volgroup: lvm-volgroup-ubuntu-vg - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount 2021-07-06 08:12:29,236 DEBUG curtin:1359 Generating storage config dependencies 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,236 DEBUG curtin:239 Validate: format-partition-sda1:SourceType:format -> (DepId:partition-sda1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sda1:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: format-partition-sda2:SourceType:format -> (DepId:partition-sda2 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sda2:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: format-lvm-partition-ubuntu-lv:SourceType:format -> (DepId:lvm-partition-ubuntu-lv DepType:lvm_partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: lvm-partition-ubuntu-lv:SourceType:lvm_partition -> (DepId:lvm-volgroup-ubuntu-vg DepType:lvm_volgroup) in SourceDeps:{'lvm_volgroup'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: lvm-volgroup-ubuntu-vg:SourceType:lvm_volgroup -> (DepId:partition-sda3 DepType:partition) in SourceDeps:{'dm_crypt', 'bcache', 'partition', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sda3:SourceType:partition -> (DepId:disk-sda DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: mount-partition-sdb1:SourceType:mount -> (DepId:format-partition-sdb1 DepType:format) in SourceDeps:{'format'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: format-partition-sdb1:SourceType:format -> (DepId:partition-sdb1 DepType:partition) in SourceDeps:{'raid', 'dm_crypt', 'bcache', 'partition', 'disk', 'lvm_partition'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:239 Validate: partition-sdb1:SourceType:partition -> (DepId:disk-sdb DepType:disk) in SourceDeps:{'partition', 'bcache', 'disk', 'raid'} ? result=True 2021-07-06 08:12:29,237 DEBUG curtin:1365 Merging storage config dependencies 2021-07-06 08:12:29,241 DEBUG curtin:1372 Merged storage config: storage: config: - id: disk-sda path: /dev/sda ptable: gpt serial: SATA_SSD_4D4507640D2701095431 type: disk wwn: '0x5000000000000000' - id: disk-sdb path: /dev/sdb ptable: dos serial: USB_FLASH_DRIVE_B4CE8395-0:0 type: disk - device: disk-sdb flag: boot id: partition-sdb1 number: 1 offset: 1048576 size: 7779385344 type: partition - fstype: vfat id: format-partition-sdb1 type: format volume: partition-sdb1 - device: format-partition-sdb1 id: mount-partition-sdb1 path: /cdrom type: mount - device: disk-sda flag: boot id: partition-sda1 number: 1 offset: 1048576 size: 536870912 type: partition - device: disk-sda flag: linux id: partition-sda2 number: 2 offset: 537919488 size: 1073741824 type: partition - device: disk-sda flag: linux id: partition-sda3 number: 3 offset: 1611661312 size: 118420930560 type: partition - fstype: vfat id: format-partition-sda1 type: format volume: partition-sda1 - fstype: ext4 id: format-partition-sda2 type: format uuid: f90f0594-1f78-4dc3-99e4-26e6f2ac47ed volume: partition-sda2 - devices: - partition-sda3 id: lvm-volgroup-ubuntu-vg name: ubuntu-vg type: lvm_volgroup - id: lvm-partition-ubuntu-lv name: ubuntu-lv size: 59210989568B type: lvm_partition volgroup: lvm-volgroup-ubuntu-vg - fstype: ext4 id: format-lvm-partition-ubuntu-lv type: format uuid: 950c70ab-7107-4714-a6c0-f113e1113bf6 volume: lvm-partition-ubuntu-lv version: 1 2021-07-06 08:12:29,241 DEBUG subiquity.models.filesystem:1567 exclusions {'disk-sdb', 'format-partition-sdb1', 'partition-sdb1'} 2021-07-06 08:12:29,241 DEBUG root:39 finish: subiquity/Filesystem/_probe/probe_once: SUCCESS: restricted=False 2021-07-06 08:12:29,241 DEBUG root:39 finish: subiquity/Filesystem/_probe: SUCCESS: 2021-07-06 08:13:42,413 DEBUG root:39 start: subiquity/Mirror/POST: "http://free.nchc.org.tw/ubuntu/" 2021-07-06 08:13:42,414 DEBUG subiquity.models.subiquity:146 model mirror is configured, to go set() 2021-07-06 08:13:42,414 DEBUG root:39 finish: subiquity/Mirror/POST: SUCCESS: 200 null 2021-07-06 08:13:42,415 INFO aiohttp.access:206 [06/Jul/2021:08:13:42 +0000] "POST /mirror HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:13:42,416 DEBUG root:39 start: subiquity/Refresh/GET: 2021-07-06 08:13:42,417 DEBUG root:39 finish: subiquity/Refresh/GET: SUCCESS: 200 {"availability": "UNAVAILABLE", "current_snap_version": "21.01.2", "new_snap_... 2021-07-06 08:13:42,417 INFO aiohttp.access:206 [06/Jul/2021:08:13:42 +0000] "GET /refresh HTTP/1.1" 200 276 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:13:42,418 DEBUG root:39 start: subiquity/Filesystem/GET: 2021-07-06 08:13:42,418 DEBUG subiquity.models.filesystem:1632 mountpoints {} 2021-07-06 08:13:42,419 DEBUG root:39 finish: subiquity/Filesystem/GET: SUCCESS: 200 {"status": "DONE", "bootloader": "UEFI", "error_report": null, "orig_config":... 2021-07-06 08:13:42,419 INFO aiohttp.access:206 [06/Jul/2021:08:13:42 +0000] "GET /storage HTTP/1.1" 200 23055 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:15,922 DEBUG root:39 start: subiquity/Filesystem/POST: [{"ptable": "gpt", "serial": "SATA_SSD_4D4507640D2701095431", "wwn": "0x50000... 2021-07-06 08:14:15,923 DEBUG subiquity.models.filesystem:1567 exclusions set() 2021-07-06 08:14:15,924 DEBUG subiquity.models.subiquity:146 model filesystem is configured, to go set() 2021-07-06 08:14:15,925 DEBUG root:39 finish: subiquity/Filesystem/POST: SUCCESS: 200 null 2021-07-06 08:14:15,925 INFO aiohttp.access:206 [06/Jul/2021:08:14:15 +0000] "POST /storage HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:15,926 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "NEEDS_CONFIRMATION", "confirming_tty": "", "error": null, "cloud_i... 2021-07-06 08:14:15,926 INFO aiohttp.access:206 [06/Jul/2021:08:10:16 +0000] "GET /meta/status?cur=%22WAITING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:15,927 INFO aiohttp.access:206 [06/Jul/2021:08:14:15 +0000] "GET /identity HTTP/1.1" 200 183 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:15,928 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:14:17,725 INFO root:39 start: subiquity/Meta/confirm_POST: 2021-07-06 08:14:17,725 INFO root:39 finish: subiquity/Meta/confirm_POST: SUCCESS: 200 null 2021-07-06 08:14:17,726 INFO aiohttp.access:206 [06/Jul/2021:08:14:17 +0000] "POST /meta/confirm?tty=%22/dev/tty1%22 HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:17,727 INFO root:39 start: subiquity/Install/install/curtin_install: installing system 2021-07-06 08:14:17,727 DEBUG subiquity.server.controllers.install:188 curtin_install 2021-07-06 08:14:17,729 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.subiquity.DebconfSelectionsModel object at 0x7f83e35ede10> 2021-07-06 08:14:17,730 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.filesystem.FilesystemModel object at 0x7f83e35edb70> 2021-07-06 08:14:17,731 DEBUG subiquity.models.filesystem:1632 mountpoints {'/': 'mount-2', '/boot': 'mount-1', '/boot/efi': 'mount-0'} 2021-07-06 08:14:17,731 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.keyboard.KeyboardModel object at 0x7f83e35edba8> 2021-07-06 08:14:17,732 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.mirror.MirrorModel object at 0x7f83e35f2390> 2021-07-06 08:14:17,733 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.network.NetworkModel object at 0x7f83e35f2320> 2021-07-06 08:14:17,735 DEBUG subiquity.models.subiquity:343 merging config from <subiquity.models.proxy.ProxyModel object at 0x7f83e35f20b8> 2021-07-06 08:14:17,745 DEBUG subiquity.server.controllers.install:200 curtin install cmd: ['/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', '--showtrace', '-c', '/var/log/installer/subiquity-curtin-install.conf', 'install'] 2021-07-06 08:14:17,745 DEBUG subiquitycore.utils:74 arun_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', '--showtrace', '-c', '/var/log/installer/subiquity-curtin-install.conf', 'install'] 2021-07-06 08:14:17,752 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_ini... 2021-07-06 08:14:17,753 INFO aiohttp.access:206 [06/Jul/2021:08:14:15 +0000] "GET /meta/status?cur=%22NEEDS_CONFIRMATION%22 HTTP/1.1" 200 420 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:17,754 DEBUG root:39 start: subiquity/Identity/GET: 2021-07-06 08:14:17,754 DEBUG root:39 finish: subiquity/Identity/GET: SUCCESS: 200 {"realname": "", "username": "", "crypted_password": "", "hostname": ""} 2021-07-06 08:14:17,755 INFO aiohttp.access:206 [06/Jul/2021:08:14:17 +0000] "GET /identity HTTP/1.1" 200 258 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:14:17,755 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:14:18,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install: curtin command install 2021-07-06 08:14:18,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-early: preparing for installation 2021-07-06 08:14:18,510 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-early: SUCCESS: preparing for installation 2021-07-06 08:14:18,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning: configuring storage 2021-07-06 08:14:18,511 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin: running 'curtin block-meta simple' 2021-07-06 08:14:19,432 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: curtin command block-meta 2021-07-06 08:14:20,680 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: removing previous storage devices 2021-07-06 08:14:24,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta/clear-holders: SUCCESS: removing previous storage devices 2021-07-06 08:14:24,011 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta/: configuring disk: disk-sda 2021-07-06 08:14:24,012 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta/: SUCCESS: configuring disk: disk-sda 2021-07-06 08:14:24,012 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-0 2021-07-06 08:14:25,382 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-0 2021-07-06 08:14:25,382 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-0 2021-07-06 08:14:26,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-0 2021-07-06 08:14:26,011 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-1 2021-07-06 08:14:27,469 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-1 2021-07-06 08:14:27,469 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-1 2021-07-06 08:14:28,237 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-1 2021-07-06 08:14:28,237 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring partition: partition-2 2021-07-06 08:14:29,509 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring partition: partition-2 2021-07-06 08:14:29,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_volgroup: lvm_volgroup-0 2021-07-06 08:14:30,390 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_volgroup: lvm_volgroup-0 2021-07-06 08:14:30,391 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring lvm_partition: lvm_partition-0 2021-07-06 08:14:31,260 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring lvm_partition: lvm_partition-0 2021-07-06 08:14:31,260 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring format: format-2 2021-07-06 08:14:32,161 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring format: format-2 2021-07-06 08:14:32,162 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-2 2021-07-06 08:14:32,163 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-2 2021-07-06 08:14:32,163 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-1 2021-07-06 08:14:32,411 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-1 2021-07-06 08:14:32,412 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: configuring mount: mount-0 2021-07-06 08:14:33,009 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin/cmd-block-meta: SUCCESS: configuring mount: mount-0 2021-07-06 08:14:33,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning/builtin: SUCCESS: running 'curtin block-meta simple' 2021-07-06 08:14:33,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-partitioning: SUCCESS: configuring storage 2021-07-06 08:14:33,010 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-extract: writing install sources to disk 2021-07-06 08:14:33,010 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-extract/builtin: running 'curtin extract' 2021-07-06 08:14:34,259 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-extract/builtin/cmd-extract: curtin command extract 2021-07-06 08:14:34,260 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-extract/builtin/cmd-extract/: acquiring and extracting image from cp:///media/filesystem 2021-07-06 08:14:51,009 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-extract/builtin/cmd-extract/: SUCCESS: acquiring and extracting image from cp:///media/filesystem 2021-07-06 08:14:51,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-extract/builtin: SUCCESS: running 'curtin extract' 2021-07-06 08:14:51,010 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-extract: SUCCESS: writing install sources to disk 2021-07-06 08:14:51,010 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks: configuring installed system 2021-07-06 08:14:51,011 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/000-configure-run: running '/snap/bin/subiquity.subiquity-configure-run' 2021-07-06 08:14:51,011 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/000-configure-run: SUCCESS: running '/snap/bin/subiquity.subiquity-configure-run' 2021-07-06 08:14:51,011 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt: running '/snap/bin/subiquity.subiquity-configure-apt /snap/subiquity/2280/usr/bin/python3 true' 2021-07-06 08:14:51,269 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt/cmd-apt-config: curtin command apt-config 2021-07-06 08:14:51,759 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt/cmd-apt-config: SUCCESS: curtin command apt-config 2021-07-06 08:14:52,023 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt/cmd-in-target: curtin command in-target 2021-07-06 08:15:00,759 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt/cmd-in-target: SUCCESS: curtin command in-target 2021-07-06 08:15:00,760 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/001-configure-apt: SUCCESS: running '/snap/bin/subiquity.subiquity-configure-apt /snap/subiquity/2280/usr/bin/python3 true' 2021-07-06 08:15:00,760 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin: running 'curtin curthooks' 2021-07-06 08:15:01,730 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks: curtin command curthooks 2021-07-06 08:15:01,731 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: configuring apt configuring apt 2021-07-06 08:15:02,009 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-apt-config: SUCCESS: configuring apt configuring apt 2021-07-06 08:15:02,010 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: installing missing packages 2021-07-06 08:15:02,010 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/: Installing packages on target system: ['efibootmgr', 'grub-efi-amd64', 'grub-efi-amd64-signed', 'shim-signed'] 2021-07-06 08:15:08,032 DEBUG root:39 start: subiquity/Identity/POST: {"realname": "Thomas Mei", "username": "thomas", "crypted_password": "$6$aT/o... 2021-07-06 08:15:08,033 DEBUG subiquity.models.subiquity:146 model identity is configured, to go set() 2021-07-06 08:15:08,033 DEBUG root:39 finish: subiquity/Identity/POST: SUCCESS: 200 null 2021-07-06 08:15:08,033 INFO aiohttp.access:206 [06/Jul/2021:08:15:08 +0000] "POST /identity HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:15:08,034 DEBUG root:39 start: subiquity/SSH/GET: 2021-07-06 08:15:08,034 DEBUG root:39 finish: subiquity/SSH/GET: SUCCESS: 200 {"install_server": false, "allow_pw": true, "authorized_keys": []} 2021-07-06 08:15:08,035 INFO aiohttp.access:206 [06/Jul/2021:08:15:08 +0000] "GET /ssh HTTP/1.1" 200 252 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:15:08,509 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/: SUCCESS: Installing packages on target system: ['efibootmgr', 'grub-efi-amd64', 'grub-efi-amd64-signed', 'shim-signed'] 2021-07-06 08:15:08,510 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-missing-packages: SUCCESS: installing missing packages 2021-07-06 08:15:08,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: configuring iscsi service 2021-07-06 08:15:08,510 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-iscsi-service: SUCCESS: configuring iscsi service 2021-07-06 08:15:08,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: configuring raid (mdadm) service 2021-07-06 08:15:08,511 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-mdadm-service: SUCCESS: configuring raid (mdadm) service 2021-07-06 08:15:08,511 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: installing kernel 2021-07-06 08:15:49,902 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/installing-kernel: SUCCESS: installing kernel 2021-07-06 08:15:49,903 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: setting up swap 2021-07-06 08:15:49,904 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/setting-up-swap: SUCCESS: setting up swap 2021-07-06 08:15:49,904 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: apply networking config 2021-07-06 08:15:49,905 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/apply-networking-config: SUCCESS: apply networking config 2021-07-06 08:15:49,906 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: writing etc/fstab 2021-07-06 08:15:49,906 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/writing-etc-fstab: SUCCESS: writing etc/fstab 2021-07-06 08:15:49,907 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: configuring multipath 2021-07-06 08:15:50,509 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-multipath: SUCCESS: configuring multipath 2021-07-06 08:15:50,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: updating packages on target system 2021-07-06 08:15:50,510 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/system-upgrade: SUCCESS: updating packages on target system 2021-07-06 08:15:50,510 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: configuring pollinate user-agent on target 2021-07-06 08:15:50,510 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/pollinate-user-agent: SUCCESS: configuring pollinate user-agent on target 2021-07-06 08:15:50,511 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: updating initramfs configuration 2021-07-06 08:15:55,557 DEBUG root:39 start: subiquity/SSH/POST: {"install_server": true, "allow_pw": true, "authorized_keys": []} 2021-07-06 08:15:55,557 DEBUG subiquity.models.subiquity:146 model ssh is configured, to go set() 2021-07-06 08:15:55,557 DEBUG root:39 finish: subiquity/SSH/POST: SUCCESS: 200 null 2021-07-06 08:15:55,558 INFO aiohttp.access:206 [06/Jul/2021:08:15:55 +0000] "POST /ssh HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:15:55,559 DEBUG root:39 start: subiquity/SnapList/GET: 2021-07-06 08:15:55,563 DEBUG root:39 finish: subiquity/SnapList/GET: SUCCESS: 200 {"status": "DONE", "snaps": [{"name": "microk8s", "summary": "Lightweight Kub... 2021-07-06 08:15:55,563 INFO aiohttp.access:206 [06/Jul/2021:08:15:55 +0000] "GET /snaplist HTTP/1.1" 200 49110 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:16:07,395 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/updating-initramfs-configuration: SUCCESS: updating initramfs configuration 2021-07-06 08:16:07,395 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-bootloader: configuring target system bootloader 2021-07-06 08:16:07,396 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/install-grub: installing grub to target devices 2021-07-06 08:16:14,059 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/install-grub: SUCCESS: installing grub to target devices 2021-07-06 08:16:14,059 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin/cmd-curthooks/configuring-bootloader: SUCCESS: configuring target system bootloader 2021-07-06 08:16:14,060 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks/builtin: SUCCESS: running 'curtin curthooks' 2021-07-06 08:16:14,060 DEBUG root:39 finish: subiquity/Install/install/curtin_install/cmd-install/stage-curthooks: SUCCESS: configuring installed system 2021-07-06 08:16:14,060 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-hook: finalizing installation 2021-07-06 08:16:14,060 DEBUG root:39 start: subiquity/Install/install/curtin_install/cmd-install/stage-hook/builtin: running 'curtin hook' 2021-07-06 08:16:14,960 DEBUG subiquitycore.utils:83 arun_command ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', '--showtrace', '-c', '/var/log/installer/subiquity-curtin-install.conf', 'install'] exited with code 0 2021-07-06 08:16:14,961 DEBUG subiquity.server.controllers.install:209 curtin_install completed: 0 2021-07-06 08:16:14,961 INFO root:39 finish: subiquity/Install/install/curtin_install: SUCCESS: installing system 2021-07-06 08:16:14,961 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_WAIT", "confirming_tty": "/dev/tty1", "error": null, "cloud_i... 2021-07-06 08:16:14,962 INFO aiohttp.access:206 [06/Jul/2021:08:14:17 +0000] "GET /meta/status?cur=%22RUNNING%22 HTTP/1.1" 200 422 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:16:14,963 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:16:46,993 DEBUG root:39 start: subiquity/SnapList/POST: [] 2021-07-06 08:16:46,994 DEBUG subiquity.models.subiquity:146 model snaplist is configured, to go set() 2021-07-06 08:16:46,994 DEBUG root:39 finish: subiquity/SnapList/POST: SUCCESS: 200 null 2021-07-06 08:16:46,995 INFO aiohttp.access:206 [06/Jul/2021:08:16:46 +0000] "POST /snaplist HTTP/1.1" 200 189 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:16:52,125 DEBUG subiquity.server.controllers.install:262 waited 5.099999999999998 seconds for events to drain 2021-07-06 08:16:52,126 INFO root:39 start: subiquity/Install/install/postinstall: final system configuration 2021-07-06 08:16:52,127 DEBUG subiquity.models.filesystem:1632 mountpoints {'/': 'mount-2', '/boot': 'mount-1', '/boot/efi': 'mount-0'} 2021-07-06 08:16:52,138 DEBUG root:39 start: subiquity/Install/install/postinstall/configure_cloud_init: configuring cloud-init 2021-07-06 08:16:52,138 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "POST_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "clou... 2021-07-06 08:16:52,138 INFO aiohttp.access:206 [06/Jul/2021:08:16:14 +0000] "GET /meta/status?cur=%22POST_WAIT%22 HTTP/1.1" 200 425 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:16:52,139 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,142 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:16:52,144 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,144 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,148 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,148 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,153 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,153 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,158 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,159 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,164 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,165 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,171 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,172 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,178 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,178 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,185 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,185 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,192 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,192 DEBUG subiquitycore.utils:48 run_command called: ['chroot', '/target', 'getent', 'group'] 2021-07-06 08:16:52,199 DEBUG subiquitycore.utils:61 run_command ['chroot', '/target', 'getent', 'group'] exited with code 0 2021-07-06 08:16:52,205 DEBUG root:39 finish: subiquity/Install/install/postinstall/configure_cloud_init: SUCCESS: configuring cloud-init 2021-07-06 08:16:52,206 DEBUG root:39 start: subiquity/Install/install/postinstall/install_openssh-server: installing openssh-server 2021-07-06 08:16:52,206 DEBUG subiquitycore.utils:74 arun_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', 'system-install', '-t', '/target', '--', 'openssh-server'] 2021-07-06 08:16:58,225 DEBUG subiquitycore.utils:83 arun_command ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', 'system-install', '-t', '/target', '--', 'openssh-server'] exited with code 0 2021-07-06 08:16:58,226 DEBUG root:39 finish: subiquity/Install/install/postinstall/install_openssh-server: SUCCESS: installing openssh-server 2021-07-06 08:16:58,226 DEBUG root:39 start: subiquity/Install/install/postinstall/restore_apt_config: restoring apt configuration 2021-07-06 08:16:58,226 DEBUG subiquitycore.utils:74 arun_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', 'umount', '/target/etc/apt'] 2021-07-06 08:16:58,234 DEBUG subiquitycore.utils:83 arun_command ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', 'umount', '/target/etc/apt'] exited with code 0 2021-07-06 08:16:58,234 DEBUG subiquitycore.utils:74 arun_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', 'in-target', '-t', '/target', '--', 'apt-get', 'update'] 2021-07-06 08:17:00,725 DEBUG subiquitycore.utils:83 arun_command ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', 'in-target', '-t', '/target', '--', 'apt-get', 'update'] exited with code 0 2021-07-06 08:17:00,726 DEBUG root:39 finish: subiquity/Install/install/postinstall/restore_apt_config: SUCCESS: restoring apt configuration 2021-07-06 08:17:00,726 INFO root:39 finish: subiquity/Install/install/postinstall: SUCCESS: final system configuration 2021-07-06 08:17:00,726 DEBUG root:39 start: subiquity/Install/install/run_unattended_upgrades: downloading and installing security updates 2021-07-06 08:17:00,726 DEBUG subiquitycore.utils:94 astart_command called: ['systemd-cat', '--level-prefix=false', '--identifier=subiquity_log.2111', '/snap/subiquity/2280/usr/bin/python3', '-m', 'curtin', 'in-target', '-t', '/target', '--', 'unattended-upgrades', '-v'] 2021-07-06 08:17:00,729 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "UU_RUNNING", "confirming_tty": "/dev/tty1", "error": null, "cloud_... 2021-07-06 08:17:00,730 INFO aiohttp.access:206 [06/Jul/2021:08:16:52 +0000] "GET /meta/status?cur=%22POST_RUNNING%22 HTTP/1.1" 200 423 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:17:00,732 INFO root:39 start: subiquity/Meta/status_GET: 2021-07-06 08:19:49,731 DEBUG root:39 finish: subiquity/Install/install/run_unattended_upgrades: SUCCESS: downloading and installing security updates 2021-07-06 08:19:49,731 DEBUG root:39 finish: subiquity/Install/install: SUCCESS: 2021-07-06 08:19:49,731 INFO root:39 finish: subiquity/Meta/status_GET: SUCCESS: 200 {"state": "DONE", "confirming_tty": "/dev/tty1", "error": null, "cloud_init_o... 2021-07-06 08:19:49,732 INFO aiohttp.access:206 [06/Jul/2021:08:17:00 +0000] "GET /meta/status?cur=%22UU_RUNNING%22 HTTP/1.1" 200 417 "-" "Python/3.6 aiohttp/3.7.3" 2021-07-06 08:19:49,732 DEBUG root:39 start: subiquity/Late/run: 2021-07-06 08:19:49,732 DEBUG root:39 finish: subiquity/Late/run: SUCCESS: 2021-07-06 08:19:49,732 DEBUG root:39 start: subiquity/Reboot/copy_logs_to_target: 2021-07-06 08:19:49,732 DEBUG subiquitycore.utils:74 arun_command called: ['cp', '-aT', '/var/log/installer', '/target/var/log/installer']

| ver. 1.4 | Github | . | PHP 7.4.3-4ubuntu2.24 | Генерация страницы: 0 | proxy | phpinfo | Настройка